FreeBSD 8.1 and HAST

Sat Jun 12 11:43:34 UTC 2010

Thanks for your quick responce!

> Is this 8.0 or 8-STABLE?
First I did on 8.0-Release and now csuped to 8.1-Prerelease
Both same behaiver.

hastd -dd log info:
Follwoing are debug.log Sorry bit long!

9.0 current

un 12 18:53:52 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:53:52 fw01B hastd: tcp4://192.168.0.240:32772: resource=zfshast
Jun 12 18:53:57 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:53:57 fw01B hastd: tcp4://192.168.0.240:35907: resource=zfshast
Jun 12 18:54:02 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:02 fw01B hastd: tcp4://192.168.0.240:41046: resource=zfshast
Jun 12 18:54:07 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:07 fw01B hastd: tcp4://192.168.0.240:24170: resource=zfshast
Jun 12 18:54:12 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:12 fw01B hastd: tcp4://192.168.0.240:58260: resource=zfshast
Jun 12 18:54:17 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:17 fw01B hastd: tcp4://192.168.0.240:62353: resource=zfshast
Jun 12 18:54:22 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:22 fw01B hastd: tcp4://192.168.0.240:45572: resource=zfshast
Jun 12 18:54:27 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:27 fw01B hastd: tcp4://192.168.0.240:40139: resource=zfshast
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) Initial connection from

tcp4://192.168.0.240:40139.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) Incoming connection from

tcp4://192.168.0.240:40139 configured.
Jun 12 18:54:27 fw01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 18:54:27 fw01B hastd: tcp4://192.168.0.240:16787: resource=zfshast
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) Outgoing connection to

tcp4://192.168.0.240:16787 configured.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) Obtained info about 
/dev/ad4p4.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) Locked /dev/ad4p4.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: Taking request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: No requests, 
waiting.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: Taking free 
request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x8014132e0) 
Got request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: Taking request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: No requests, 
waiting.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x8014132e0) 
Got request

header: WRITE(0, 131072).
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x8014132e0) 
Moving

request to the disk queue.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: Taking free 
request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x801413290) 
Got request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) Local activemap cleared.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: (0x8014132e0) 
Got request:

WRITE(0, 131072).
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: (0x8014132e0) 
Moving

request to the send queue.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: Taking request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: No requests, 
waiting.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: (0x8014132e0) 
Got request:

WRITE(0, 131072).
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: (0x8014132e0) 
Moving

request to the free queue.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: Taking request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: No requests, 
waiting.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x801413290) 
Got request

header: WRITE(131072, 131072).
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x801413290) 
Moving

request to the disk queue.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: Taking free 
request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x801413240) 
Got request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: (0x801413290) 
Got request:

WRITE(131072, 131072).
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: (0x801413290) 
Moving

request to the send queue.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: Taking request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: No requests, 
waiting.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: (0x801413290) 
Got request:

WRITE(131072, 131072).
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) disk: (0x801413290) 
Moving

request to the free queue.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: Taking request.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) send: No requests, 
waiting.
Jun 12 18:54:27 fw01B hastd: [zfshast] (secondary) recv: (0x801413240) 
Got request

header: WRITE(262144, 131072).

8.1-Prerelease

Jun 12 20:00:07 sv01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 20:00:07 sv01B hastd: tcp4://192.168.0.240:63762: resource=zfshast
Jun 12 20:00:12 sv01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 20:00:12 sv01B hastd: tcp4://192.168.0.240:22890: resource=zfshast
Jun 12 20:00:17 sv01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 20:00:17 sv01B hastd: tcp4://192.168.0.240:36449: resource=zfshast
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) Initial connection from

tcp4://192.168.0.240:36449.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) Incoming connection from

tcp4://192.168.0.240:36449 configured.
Jun 12 20:00:17 sv01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 20:00:17 sv01B hastd: tcp4://192.168.0.240:39312: resource=zfshast
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) Outgoing connection to

tcp4://192.168.0.240:39312 configured.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) Obtained info about 
/dev/ad4p4.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) Locked /dev/ad4p4.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) recv: Taking free 
request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: Taking request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: No requests, 
waiting.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) recv: (0x8011f52e0) 
Got request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) send: Taking request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) send: No requests, 
waiting.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) recv: (0x8011f52e0) 
Got request

header: WRITE(0, 131072).
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) recv: (0x8011f52e0) 
Moving

request to the disk queue.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) recv: Taking free 
request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) recv: (0x8011f5290) 
Got request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) Local activemap cleared.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: (0x8011f52e0) 
Got request:

WRITE(0, 131072).
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: (0x8011f52e0) 
Moving

request to the send queue.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: Taking request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: No requests, 
waiting.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) send: (0x8011f52e0) 
Got request:

WRITE(0, 131072).
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) disk: (0x8011f52e0) 
Moving

request to the free queue.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) send: Taking request.
Jun 12 20:00:17 sv01B hastd: [zfshast] (secondary) send: No requests, 
waiting.
Jun 12 20:00:22 sv01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 20:00:22 sv01B hastd: tcp4://192.168.0.240:55777: resource=zfshast
Jun 12 20:00:22 sv01B hastd: [zfshast] (secondary) Initial connection from

tcp4://192.168.0.240:55777.
Jun 12 20:00:22 sv01B hastd: [zfshast] (secondary) Worker process exists 
(pid=768),

stopping it.
Jun 12 20:00:22 sv01B hastd: [zfshast] (secondary) Incoming connection from

tcp4://192.168.0.240:55777 configured.
Jun 12 20:00:22 sv01B hastd: Accepting connection to tcp4://0.0.0.0:8457.
Jun 12 20:00:22 sv01B hastd: tcp4://192.168.0.240:38559: resource=zfshast

0:56279
>
> So is the resource configured as secondary or not?
> Could you stop hastd and start it manually with debug turned on?
> Don't forget to mark it as secondary.
>
Yes I did it as secondary manualy.

Thanks

Hiroshi