planner: debug 1 pid 8102 ruid 34 euid 0: start at Sat Oct  8 00:31:52 2011
planner: debug 1 pid 8102 ruid 34 euid 34: rename at Sat Oct  8 00:31:52 2011
planner: time 0.079: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.079: security_handleinit(handle=0xebcf90, driver=0x7fdb27247120 (BSD))
planner: time 0.079: bind_portrange2: Try  port 852: Available   - Success
planner: time 0.079: dgram_bind: socket 3 bound to 0.0.0.0.852
planner: time 0.079: dgram_send_addr(addr=0xebcfd0, dgram=0x7fdb2724a348)
planner: time 0.079: (sockaddr_in *)0xebcfd0 = { 2, 10080, 127.0.0.1 }
planner: time 0.079: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.081: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.081: security_handleinit(handle=0xebd1b0, driver=0x7fdb27247120 (BSD))
planner: time 0.082: dgram_send_addr(addr=0xebd1f0, dgram=0x7fdb2724a348)
planner: time 0.082: (sockaddr_in *)0xebd1f0 = { 2, 10080, 10.16.0.1 }
planner: time 0.082: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.082: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.082: security_handleinit(handle=0xebeb10, driver=0x7fdb27247120 (BSD))
planner: time 0.082: dgram_send_addr(addr=0xebeb50, dgram=0x7fdb2724a348)
planner: time 0.082: (sockaddr_in *)0xebeb50 = { 2, 10080, 10.16.0.13 }
planner: time 0.083: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.083: security_getdriver(name=bsdtcp) returns 0x7fdb272471c0
planner: time 0.083: security_handleinit(handle=0xebf1c0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.083: security_streaminit(stream=0xebf2f0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.083: connect_port: Skip port 512: Owned by exec.
planner: time 0.083: connect_port: Skip port 513: Owned by login.
planner: time 0.083: connect_port: Skip port 514: Owned by shell.
planner: time 0.083: connect_port: Skip port 515: Owned by printer.
planner: time 0.083: connect_port: Try  port 516: Available   - 
planner: time 0.083: connected to 10.16.0.11.10080
planner: time 0.083: our side is 0.0.0.0.516
planner: time 0.083: try_socksize: send buffer size is 65536
planner: time 0.083: try_socksize: receive buffer size is 65536
planner: time 0.084: security_getdriver(name=bsdtcp) returns 0x7fdb272471c0
planner: time 0.084: security_handleinit(handle=0xec7de0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.084: security_streaminit(stream=0xec7fa0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.084: connect_port: Try  port 516: Available   - 
planner: time 0.086: connected to 10.16.0.20.10080
planner: time 0.086: our side is 0.0.0.0.516
planner: time 0.086: try_socksize: send buffer size is 65536
planner: time 0.086: try_socksize: receive buffer size is 65536
planner: time 0.086: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.086: security_handleinit(handle=0xed0a50, driver=0x7fdb27247120 (BSD))
planner: time 0.086: dgram_send_addr(addr=0xed0a90, dgram=0x7fdb2724a348)
planner: time 0.086: (sockaddr_in *)0xed0a90 = { 2, 10080, 10.0.0.8 }
planner: time 0.086: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.091: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.091: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 0.091: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.091: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 0.091: dgram_send_addr(addr=0xed0a90, dgram=0x7fdb2724a348)
planner: time 0.091: (sockaddr_in *)0xed0a90 = { 2, 10080, 10.0.0.8 }
planner: time 0.091: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.092: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.092: security_handleinit(handle=0xed1a30, driver=0x7fdb27247120 (BSD))
planner: time 0.092: dgram_send_addr(addr=0xed1a70, dgram=0x7fdb2724a348)
planner: time 0.092: (sockaddr_in *)0xed1a70 = { 2, 10080, 10.0.0.8 }
planner: time 0.092: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.092: security_close(handle=0xed0a50, driver=0x7fdb27247120 (BSD))
planner: time 0.093: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.093: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 0.099: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.099: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 0.104: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.104: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 0.104: dgram_send_addr(addr=0xebd1f0, dgram=0x7fdb2724a348)
planner: time 0.104: (sockaddr_in *)0xebd1f0 = { 2, 10080, 10.16.0.1 }
planner: time 0.104: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.104: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.104: security_handleinit(handle=0xed05d0, driver=0x7fdb27247120 (BSD))
planner: time 0.104: dgram_send_addr(addr=0xed0610, dgram=0x7fdb2724a348)
planner: time 0.104: (sockaddr_in *)0xed0610 = { 2, 10080, 10.16.0.1 }
planner: time 0.104: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.104: security_close(handle=0xebd1b0, driver=0x7fdb27247120 (BSD))
planner: time 0.106: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.106: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 0.107: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.107: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 0.108: security_getdriver(name=bsdtcp) returns 0x7fdb272471c0
planner: time 0.108: security_handleinit(handle=0xebd120, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.108: security_streaminit(stream=0xed3140, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.108: security_close(handle=0xebf1c0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.108: security_stream_close(0xebf2f0)
planner: time 0.112: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.112: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 0.114: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.114: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 0.117: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.118: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 0.118: dgram_send_addr(addr=0xebcfd0, dgram=0x7fdb2724a348)
planner: time 0.118: (sockaddr_in *)0xebcfd0 = { 2, 10080, 127.0.0.1 }
planner: time 0.118: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.118: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.118: security_handleinit(handle=0xed1520, driver=0x7fdb27247120 (BSD))
planner: time 0.118: dgram_send_addr(addr=0xed1560, dgram=0x7fdb2724a348)
planner: time 0.118: (sockaddr_in *)0xed1560 = { 2, 10080, 127.0.0.1 }
planner: time 0.118: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.118: security_close(handle=0xebcf90, driver=0x7fdb27247120 (BSD))
planner: time 0.119: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.119: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 0.124: security_getdriver(name=bsdtcp) returns 0x7fdb272471c0
planner: time 0.124: security_handleinit(handle=0xed0f10, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.124: security_streaminit(stream=0xedb1a0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.124: security_close(handle=0xec7de0, driver=0x7fdb272471c0 (BSDTCP))
planner: time 0.124: security_stream_close(0xec7fa0)
planner: time 0.127: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.127: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 0.216: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.216: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 0.241: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.241: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 0.241: dgram_send_addr(addr=0xebeb50, dgram=0x7fdb2724a348)
planner: time 0.241: (sockaddr_in *)0xebeb50 = { 2, 10080, 10.16.0.13 }
planner: time 0.241: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.241: security_getdriver(name=BSD) returns 0x7fdb27247120
planner: time 0.241: security_handleinit(handle=0xed1b60, driver=0x7fdb27247120 (BSD))
planner: time 0.241: dgram_send_addr(addr=0xed1ba0, dgram=0x7fdb2724a348)
planner: time 0.241: (sockaddr_in *)0xed1ba0 = { 2, 10080, 10.16.0.13 }
planner: time 0.241: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 0.241: security_close(handle=0xebeb10, driver=0x7fdb27247120 (BSD))
planner: time 0.244: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.244: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 0.261: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 0.261: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 7.330: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 7.330: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 8.184: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 8.184: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 8.654: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 8.654: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 8.773: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 8.773: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 8.826: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 8.826: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 8.836: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 8.836: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 19.318: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 19.318: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 20.398: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 20.398: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 20.407: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 20.407: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 20.593: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 20.593: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 20.593: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 20.593: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.0.0.8 }
planner: time 20.593: dgram_send_addr(addr=0xed1a70, dgram=0x7fdb2724a348)
planner: time 20.593: (sockaddr_in *)0xed1a70 = { 2, 10080, 10.0.0.8 }
planner: time 20.593: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 20.593: security_close(handle=0xed1a30, driver=0x7fdb27247120 (BSD))
planner: time 32.718: security_close(handle=0xed0f10, driver=0x7fdb272471c0 (BSDTCP))
planner: time 32.718: security_stream_close(0xedb1a0)
planner: time 36.175: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 36.175: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 38.696: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 38.696: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 38.746: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 38.746: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 38.779: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 38.779: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 38.834: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 38.834: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 38.862: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 38.862: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.13 }
planner: time 42.974: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 42.974: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 46.313: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 46.313: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 46.369: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 46.369: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 46.387: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 46.387: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 99.921: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 99.921: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 126.797: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 126.797: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 126.797: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 126.797: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 10.16.0.1 }
planner: time 126.797: dgram_send_addr(addr=0xed0610, dgram=0x7fdb2724a348)
planner: time 126.797: (sockaddr_in *)0xed0610 = { 2, 10080, 10.16.0.1 }
planner: time 126.797: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 126.797: security_close(handle=0xed05d0, driver=0x7fdb27247120 (BSD))
planner: time 273.151: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 273.151: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 289.966: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 289.966: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 707.555: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 707.557: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 926.353: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 926.353: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 936.057: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 936.057: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 937.167: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 937.167: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 937.167: dgram_recv(dgram=0x7fdb2724a348, timeout=0, fromaddr=0x7fdb2725a340)
planner: time 937.167: (sockaddr_in *)0x7fdb2725a340 = { 2, 10080, 127.0.0.1 }
planner: time 937.167: dgram_send_addr(addr=0xed1560, dgram=0x7fdb2724a348)
planner: time 937.167: (sockaddr_in *)0xed1560 = { 2, 10080, 127.0.0.1 }
planner: time 937.167: dgram_send_addr: 0x7fdb2724a348->socket = 3
planner: time 937.168: security_close(handle=0xed1520, driver=0x7fdb27247120 (BSD))
planner: time 1002.817: security_close(handle=0xebd120, driver=0x7fdb272471c0 (BSDTCP))
planner: time 1002.817: security_stream_close(0xed3140)
planner: time 9000.824: security_seterror(handle=0xed1b60, driver=0x7fdb27247120 (BSD) error=timeout waiting for REP)
planner: time 9000.850: security_close(handle=0xed1b60, driver=0x7fdb27247120 (BSD))
planner: time 9000.942: pid 8102 finish time Sat Oct  8 03:01:53 2011
