12:23:38.111 [20532] <4> ROBOT_DEBUG enabled: STARTING TLDD DAEMON 12:23:38.112 [20532] <2> main: reading nbconf 12:23:38.113 [20532] <2> main: initializing emm interface 12:23:38.128 [20532] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:23:38.128 [20532] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:23:38.128 [20532] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:23:38.129 [20532] <2> Orb::init: initializing ORB EMMlib_Orb with: tldd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741) 12:23:38.132 [20532] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:23:38.132 [20532] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:23:38.132 [20532] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:23:38.133 [20532] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437) 12:23:40.044 [20532] <5> InitTld: Device=0, TLD=0, DRIVE=1 12:23:40.139 [20536] <4> ROBOT_DEBUG enabled: STARTING TLDCD DAEMON 12:23:40.142 [20536] <4> tldcd: Host name is his1 12:23:40.154 [20538] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:23:40.154 [20538] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:23:40.154 [20538] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:23:40.155 [20538] <2> Orb::init: initializing ORB EMMlib_Orb with: tldcd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741) 12:23:40.158 [20538] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:23:40.158 [20538] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:23:40.158 [20538] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:23:40.160 [20538] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437) 12:23:43.213 [20538] <2> getsockbound: service=tldcd protocol=tcp port=13711 12:23:43.214 [20538] <5> tldcd:listen_loop: Ready for connections 12:23:45.045 [20532] <2> SpawnChild: TLD(0) Creating Child Process 12:23:45.046 [20541] <4> robotd_connect: connect to host his1 service tldcd automatically 12:23:45.046 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:23:45.046 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:23:45.046 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:23:45.047 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:23:45.047 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:23:45.047 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:23:45.047 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:23:45.047 [20541] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:23:45.051 [20541] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 0 0x00000000 12:23:45.052 [20541] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 12:23:45.052 [20541] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: tldcd 12:23:45.093 [20538] <6> tldcd:listen_loop: select: selectret = 1, error = 2, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:23:45.093 [20538] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1257: hash_str1: NULL 12:23:45.093 [20538] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1258: hash_str2: 12:23:45.093 [20538] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1771: hash_str1: NULL 12:23:45.093 [20538] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1773: hash_str2: 12:23:45.093 [20541] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4238: in progress connect: 0 0x00000000 12:23:45.093 [20538] <2> mm_accept_daemon_socket: netconf.c.1735: accept()ed vnetd connection from 192.168.1.13 12:23:45.093 [20538] <6> tldcd:listen_loop: accept: newfd = 13, error = 0, timersig = 0 12:23:45.093 [20541] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4241: connect: async CONNECT FROM 192.168.1.13.54010 TO 192.168.1.13.13724 fd = 0 12:23:45.093 [20538] <4> peer_hostname: Connection from host his1, 192.168.1.13, port 54010 12:23:45.093 [20541] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:23:45.093 [20541] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:23:45.093 [20541] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:23:45.093 [20541] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.878: Ignoring VxSS authentication: 2 0x00000002 12:23:45.093 [20541] <2> robotd_connect: Not using VxSS authentication. 12:23:45.093 [20538] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.494: VxSS magic: 789604432 0x2f106850 12:23:45.093 [20538] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.495: remote_vxss: 9 0x00000009 12:23:45.093 [20538] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:23:45.093 [20538] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:23:45.093 [20538] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:23:45.093 [20538] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.537: Ignoring VxSS authentication: 2 0x00000002 12:23:45.093 [20538] <2> robotd_check_magic: Not using VxSS authentication. 12:23:45.093 [20538] <6> tldcd:process_request: tldcd.c.3062, process_request(), received command=9, from peername=his1, version 50 12:23:45.094 [20538] <6> tldcd:initialize_robot: received ROBOT_INITIALIZE request for robot 0 12:23:45.094 [20538] <6> tldcd:initialize_robot: Checking for TLDCD_MULTIPATH file: /usr/openv/volmgr/database/TLDCD_MULTIPATH 12:23:45.094 [20538] <6> tldcd:initialize_robot_hardware: command_init on robot 0 12:23:45.094 [20538] <5> tldcd:command_init: TLD(0) opening robotic path /dev/sg3 12:23:45.209 [20538] <3> tldcd:mode_sense: Device geometry: NumDrives = 1 at address 32 12:23:45.209 [20538] <3> tldcd:mode_sense: --> NumSlots = 8 at address 256 12:23:45.209 [20538] <3> tldcd:mode_sense: --> NumTransports = 1 at address 0 12:23:45.209 [20538] <3> tldcd:mode_sense: --> NumIE = 0 at address 0 12:23:45.211 [20538] <6> tldcd:inquiry: Read device table for DELL PV-124T 0090, type 8, slots 8 and ie 0 12:23:45.211 [20538] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:974> search robot list (length=388) for DELL PV-124T, type 8 12:23:45.211 [20538] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:1227> found match: "DELL PV-124T" DELL PV-124T 12:23:45.211 [20538] <5> tldcd:inquiry: inquiry() function processing library DELL PV-124T 0090: 12:23:45.211 [20538] <6> tldcd:initialize_robot_hardware: add drive list for robot 0 12:23:45.211 [20538] <5> tldcd:initialize_robot_hardware: TLD(0) NumDrives = 1, FirstDriveAddr = 32 12:23:45.211 [20538] <5> tldcd:initialize_robot_hardware: TLD(0) NumSlots = 8, FirstSlotAddr = 256 12:23:45.211 [20538] <5> tldcd:initialize_robot_hardware: TLD(0) NumIE = 0, InportAddr = 0, OutportAddr = 0 12:23:45.211 [20541] <4> TldChildExit: Child process terminated normally 12:23:45.216 [20532] <5> GetResponseStatus: DecodeQuery() Actual status: STATUS_SUCCESS 12:23:45.216 [20532] <5> DecodeQuery: TLD(0) going to UP state 12:29:26.507 [20538] <6> tldcd:listen_loop: select: selectret = 1, error = 0, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:29:26.507 [20538] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1257: hash_str1: NULL 12:29:26.507 [20538] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1258: hash_str2: 12:29:26.507 [20538] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1771: hash_str1: NULL 12:29:26.507 [20538] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1773: hash_str2: 12:29:26.507 [20538] <2> mm_accept_daemon_socket: netconf.c.1735: accept()ed vnetd connection from 192.168.1.13 12:29:26.507 [20538] <6> tldcd:listen_loop: accept: newfd = 13, error = 0, timersig = 0 12:29:26.507 [20538] <4> peer_hostname: Connection from host his1, 192.168.1.13, port 52964 12:29:26.507 [20538] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.494: VxSS magic: 789604432 0x2f106850 12:29:26.507 [20538] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.495: remote_vxss: 10 0x0000000a 12:29:26.507 [20538] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:29:26.507 [20538] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:29:26.507 [20538] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:29:26.507 [20538] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.537: Ignoring VxSS authentication: 2 0x00000002 12:29:26.507 [20538] <2> robotd_check_magic: Not using VxSS authentication. 12:29:26.507 [20538] <6> tldcd:process_request: tldcd.c.3062, process_request(), received command=10, from peername=his1, version 50 12:29:26.507 [20538] <5> tldcd:process_request: TERMINATION requested from his1 12:29:27.786 [21155] <4> ROBOT_DEBUG enabled: STARTING TLDCD DAEMON 12:29:27.789 [21155] <4> tldcd: Host name is his1 12:29:27.789 [21155] <4> robotd_connect: connect to host his1 service tldcd automatically 12:29:27.789 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:29:27.789 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:29:27.789 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:29:27.789 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:29:27.790 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:29:27.790 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:29:27.790 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:29:27.790 [21155] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:29:27.793 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 0 0x00000000 12:29:27.794 [21155] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 12:29:27.794 [21155] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: tldcd 12:29:29.509 [20538] <6> tldcd:TermTldcd: Daemon has terminated on request 12:29:29.509 [21155] <2> vnet_pop_byte: ../../libvlibs/vnet.c.187: errno: 115 0x00000073 12:29:29.509 [21155] <2> vnet_pop_byte: ../../libvlibs/vnet.c.189: Function failed: 9 0x00000009 12:29:29.509 [21155] <2> vnet_pop_string: ../../libvlibs/vnet.c.269: Function failed: 9 0x00000009 12:29:29.509 [21155] <2> vnet_pop_signed: ../../libvlibs/vnet.c.313: Function failed: 9 0x00000009 12:29:29.509 [21155] <2> vnet_pop_status: ../../libvlibs/vnet.c.391: Function failed: 9 0x00000009 12:29:29.509 [21155] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2084: status: 9 0x00000009 12:29:29.509 [21155] <2> do_vnetd_service: ../../libvlibs/vnet_vnetd.c.4444: vnet_vnetd_service_socket failed: 9 0x00000009 12:29:29.509 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4226: do_vnetd_service failed: 9 0x00000009 12:29:29.509 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 1 0x00000001 12:29:29.509 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4250: getsockopt SO_ERROR returned: 111 0x0000006f 12:29:30.509 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 1 0x00000001 12:29:30.509 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4250: getsockopt SO_ERROR returned: 111 0x0000006f 12:29:32.510 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 1 0x00000001 12:29:32.510 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4250: getsockopt SO_ERROR returned: 111 0x0000006f 12:29:36.511 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 1 0x00000001 12:29:36.511 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4250: getsockopt SO_ERROR returned: 111 0x0000006f 12:29:44.513 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 1 0x00000001 12:29:44.513 [21155] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4250: getsockopt SO_ERROR returned: 111 0x0000006f 12:29:44.513 [21155] <2> vnet_connect_to_service_or_vnetd: ../../libvlibs/vnet_vnetd.c.3584: vnet_async_connect failed: 18 0x00000012 12:29:44.513 [21155] <2> mm_connect_to_vnetd_or_legacy: netconf.c.2335: vnet_connect_to_vnetd_or_service failed: 18 12:29:44.513 [21155] <16> robotd_connect: unable to connect to robotic daemon automatically: Connection refused (111) 12:29:44.513 [21155] <16> robotd_connect: unable to connect to tldcd: Connection refused (111) 12:29:44.513 [21155] <16> robotd_connect: cannot connect to tldcd on host his1: cannot connect to robotic software daemon (42) 12:30:52.274 [21813] <4> ROBOT_DEBUG enabled: STARTING TLDD DAEMON 12:30:52.274 [21813] <2> main: reading nbconf 12:30:52.276 [21813] <2> main: initializing emm interface 12:30:52.290 [21813] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:30:52.290 [21813] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:30:52.290 [21813] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:30:52.291 [21813] <2> Orb::init: initializing ORB EMMlib_Orb with: tldd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741) 12:30:52.295 [21813] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:30:52.295 [21813] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:30:52.295 [21813] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:30:52.296 [21813] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437) 12:30:54.205 [21813] <5> InitTld: Device=0, TLD=0, DRIVE=1 12:30:54.298 [21853] <4> ROBOT_DEBUG enabled: STARTING TLDCD DAEMON 12:30:54.301 [21853] <4> tldcd: Host name is his1 12:30:54.313 [21855] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:30:54.313 [21855] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:30:54.313 [21855] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:30:54.314 [21855] <2> Orb::init: initializing ORB EMMlib_Orb with: tldcd -ORBSvcConfDirective "-ORBDottedDecimalAddresses 0" -ORBSvcConfDirective "static PBXIOP_Factory '-enable_keepalive'" -ORBSvcConfDirective "static EndpointSelectorFactory ''" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory PBXIOP_Factory'" -ORBSvcConfDirective "static Resource_Factory '-ORBProtocolFactory IIOP_Factory'" -ORBSvcConfDirective "static PBXIOP_Evaluator_Factory '-orb EMMlib_Orb'" -ORBSvcConfDirective "static Resource_Factory '-ORBConnectionCacheMax 1024 '" -ORBSvcConf /dev/null -ORBSvcConfDirective "static Server_Strategy_Factory '-ORBMaxRecvGIOPPayloadSize 268435456'"(Orb.cpp:741) 12:30:54.317 [21855] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:30:54.317 [21855] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:30:54.317 [21855] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:30:54.319 [21855] <2> EndpointSelector::select_endpoint: performing call with the only endpt available!(Endpoint_Selector.cpp:437) 12:30:57.365 [21855] <2> getsockbound: service=tldcd protocol=tcp port=13711 12:30:57.366 [21855] <5> tldcd:listen_loop: Ready for connections 12:30:59.207 [21813] <2> SpawnChild: TLD(0) Creating Child Process 12:30:59.207 [21976] <4> robotd_connect: connect to host his1 service tldcd automatically 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:30:59.208 [21976] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:30:59.212 [21976] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 0 0x00000000 12:30:59.213 [21976] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 12:30:59.213 [21976] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: tldcd 12:30:59.253 [21855] <6> tldcd:listen_loop: select: selectret = 1, error = 2, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:30:59.253 [21855] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1257: hash_str1: NULL 12:30:59.253 [21855] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1258: hash_str2: 12:30:59.253 [21855] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1771: hash_str1: NULL 12:30:59.253 [21855] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1773: hash_str2: 12:30:59.253 [21855] <2> mm_accept_daemon_socket: netconf.c.1735: accept()ed vnetd connection from 192.168.1.13 12:30:59.253 [21855] <6> tldcd:listen_loop: accept: newfd = 13, error = 0, timersig = 0 12:30:59.253 [21855] <4> peer_hostname: Connection from host his1, 192.168.1.13, port 54158 12:30:59.254 [21976] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4238: in progress connect: 0 0x00000000 12:30:59.254 [21976] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4241: connect: async CONNECT FROM 192.168.1.13.54158 TO 192.168.1.13.13724 fd = 0 12:30:59.254 [21976] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:30:59.254 [21976] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:30:59.254 [21976] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:30:59.254 [21976] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.878: Ignoring VxSS authentication: 2 0x00000002 12:30:59.254 [21976] <2> robotd_connect: Not using VxSS authentication. 12:30:59.254 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.494: VxSS magic: 789604432 0x2f106850 12:30:59.255 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.495: remote_vxss: 9 0x00000009 12:30:59.255 [21855] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:30:59.255 [21855] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:30:59.255 [21855] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:30:59.255 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.537: Ignoring VxSS authentication: 2 0x00000002 12:30:59.255 [21855] <2> robotd_check_magic: Not using VxSS authentication. 12:30:59.255 [21855] <6> tldcd:process_request: tldcd.c.3062, process_request(), received command=9, from peername=his1, version 50 12:30:59.255 [21855] <6> tldcd:initialize_robot: received ROBOT_INITIALIZE request for robot 0 12:30:59.255 [21855] <6> tldcd:initialize_robot: Checking for TLDCD_MULTIPATH file: /usr/openv/volmgr/database/TLDCD_MULTIPATH 12:30:59.255 [21855] <6> tldcd:initialize_robot_hardware: command_init on robot 0 12:30:59.255 [21855] <5> tldcd:command_init: TLD(0) opening robotic path /dev/sg3 12:30:59.374 [21855] <3> tldcd:mode_sense: Device geometry: NumDrives = 1 at address 32 12:30:59.374 [21855] <3> tldcd:mode_sense: --> NumSlots = 8 at address 256 12:30:59.374 [21855] <3> tldcd:mode_sense: --> NumTransports = 1 at address 0 12:30:59.374 [21855] <3> tldcd:mode_sense: --> NumIE = 0 at address 0 12:30:59.376 [21855] <6> tldcd:inquiry: Read device table for DELL PV-124T 0090, type 8, slots 8 and ie 0 12:30:59.376 [21855] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:974> search robot list (length=388) for DELL PV-124T, type 8 12:30:59.376 [21855] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:1227> found match: "DELL PV-124T" DELL PV-124T 12:30:59.376 [21855] <5> tldcd:inquiry: inquiry() function processing library DELL PV-124T 0090: 12:30:59.376 [21855] <6> tldcd:initialize_robot_hardware: add drive list for robot 0 12:30:59.376 [21855] <5> tldcd:initialize_robot_hardware: TLD(0) NumDrives = 1, FirstDriveAddr = 32 12:30:59.376 [21855] <5> tldcd:initialize_robot_hardware: TLD(0) NumSlots = 8, FirstSlotAddr = 256 12:30:59.376 [21855] <5> tldcd:initialize_robot_hardware: TLD(0) NumIE = 0, InportAddr = 0, OutportAddr = 0 12:30:59.376 [21976] <4> TldChildExit: Child process terminated normally 12:30:59.381 [21813] <5> GetResponseStatus: DecodeQuery() Actual status: STATUS_SUCCESS 12:30:59.381 [21813] <5> DecodeQuery: TLD(0) going to UP state 12:31:46.287 [21813] <4> AddTldLtiReqEntry: Processing ROBOT_MOUNT request... 12:31:46.287 [21813] <2> AddTldLtiReqEntry: mount request specified Drive Name IBM.ULTRIUM-TD3.000 12:31:46.287 [21813] <4> TldGetDriveForMountReq: Got drive id 0 for drive IBM.ULTRIUM-TD3.000 12:31:46.287 [21813] <2> ProcessTable: found active request id 0. Child pid = 0 flags = 0 request type 1 12:31:46.287 [21813] <2> ProcessTable: ROBOT_MOUNT requested ... set this drive IN USE 12:31:46.287 [21813] <5> MountTape: TLD(0) MountTape 0192L3 on drive 1, from slot 2 12:31:46.287 [21813] <2> SpawnChild: TLD(0) Creating Child Process 12:31:46.288 [22167] <2> parse_tape_path: ltid request: /dev/nst0 , shared memory: /dev/nst0 12:31:46.288 [22167] <4> io_open: Drive Path = /dev/nst0 12:31:59.435 [22167] <4> robotd_connect: connect to host his1 service tldcd automatically 12:31:59.435 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:31:59.435 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:31:59.435 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:31:59.435 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:31:59.436 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:31:59.436 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:31:59.436 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:31:59.436 [22167] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:31:59.440 [22167] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 0 0x00000000 12:31:59.441 [22167] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 12:31:59.441 [22167] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: tldcd 12:31:59.481 [21855] <6> tldcd:listen_loop: select: selectret = 1, error = 0, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:31:59.481 [21855] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1257: hash_str1: NULL 12:31:59.481 [21855] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1258: hash_str2: 12:31:59.481 [21855] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1771: hash_str1: NULL 12:31:59.481 [21855] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1773: hash_str2: 12:31:59.481 [21855] <2> mm_accept_daemon_socket: netconf.c.1735: accept()ed vnetd connection from 192.168.1.13 12:31:59.481 [21855] <6> tldcd:listen_loop: accept: newfd = 13, error = 0, timersig = 0 12:31:59.481 [21855] <4> peer_hostname: Connection from host his1, 192.168.1.13, port 48426 12:31:59.482 [22167] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4238: in progress connect: 0 0x00000000 12:31:59.482 [22167] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4241: connect: async CONNECT FROM 192.168.1.13.48426 TO 192.168.1.13.13724 fd = 0 12:31:59.482 [22167] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:31:59.482 [22167] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:31:59.482 [22167] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:31:59.482 [22167] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.878: Ignoring VxSS authentication: 2 0x00000002 12:31:59.482 [22167] <2> robotd_connect: Not using VxSS authentication. 12:31:59.482 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.494: VxSS magic: 789604432 0x2f106850 12:31:59.482 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.495: remote_vxss: 1 0x00000001 12:31:59.482 [21855] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:31:59.482 [21855] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:31:59.482 [21855] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:31:59.482 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.537: Ignoring VxSS authentication: 2 0x00000002 12:31:59.482 [21855] <2> robotd_check_magic: Not using VxSS authentication. 12:31:59.482 [21855] <6> tldcd:process_request: tldcd.c.3062, process_request(), received command=1, from peername=his1, version 50 12:31:59.482 [21855] <5> tldcd:mount_unmount_drive: Processing MOUNT, TLD(0) drive 1, slot 2, barcode 000192L3 , vsn 0192L3 12:31:59.483 [22193] <5> tldcd:command_init: TLD(0) opening robotic path /dev/sg3 12:31:59.593 [22193] <3> tldcd:mode_sense: Device geometry: NumDrives = 1 at address 32 12:31:59.593 [22193] <3> tldcd:mode_sense: --> NumSlots = 8 at address 256 12:31:59.593 [22193] <3> tldcd:mode_sense: --> NumTransports = 1 at address 0 12:31:59.593 [22193] <3> tldcd:mode_sense: --> NumIE = 0 at address 0 12:31:59.596 [22193] <6> tldcd:inquiry: Read device table for DELL PV-124T 0090, type 8, slots 8 and ie 0 12:31:59.596 [22193] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:974> search robot list (length=388) for DELL PV-124T, type 8 12:31:59.596 [22193] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:1227> found match: "DELL PV-124T" DELL PV-124T 12:31:59.596 [22193] <5> tldcd:inquiry: inquiry() function processing library DELL PV-124T 0090: 12:31:59.596 [22193] <6> tldcd:read_element_status_drive: RES drive 1 12:31:59.774 [22193] <3> tldcd:tape_in_drive: address = 32, source address = 0, source valid = 0 12:31:59.774 [22193] <3> -->: sense = 00, qual = 00, except = 0, access = 1, full = 1, invert = 0 12:31:59.774 [22193] <3> -->: voltag = , [00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ] 12:31:59.774 [22193] <6> tldcd:tape_in_drive: valid = 0, sel = 0, barcode = () 12:31:59.780 [22193] <6> tldcd:read_element_status_slot: RES storage element 5 12:31:59.940 [22193] <3> tldcd:empty_slot: address = 260, source address = 1025, source valid = 0 12:31:59.941 [22193] <3> -->: sense = 00, qual = 00, except = 0, access = 1, full = 1, invert = 0 12:31:59.941 [22193] <3> -->: voltag = 000195L3 12:31:59.941 [22193] <3> -->: voltag hex: [30 30 30 31 39 35 4C 33 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 00 00 00 00 ] 12:31:59.941 [22193] <3> tldcd:move_medium: TLD(0) cannot dismount drive 1, slot 5 already is full 12:31:59.941 [22193] <5> tldcd:tld_main: TLD(0) closing/unlocking robotic path 12:31:59.947 [22167] <16> TldChildExit: Child process terminated abnormally with error 10 12:31:59.947 [21855] <6> decode_response: sending response "EXIT_STATUS 10 0" for request on drive 1 (child_pid 22193) 12:31:59.947 [21855] <6> tldcd:listen_loop: select: selectret = -1, error = 4, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:31:59.947 [21855] <6> tldcd:check_unit_attention: command_init to check for UA on robot 0 12:31:59.951 [21813] <5> GetResponseStatus: DecodeMount: TLD(0) drive 1, Actual status: Unable to SCSI unload drive 12:32:00.057 [21855] <3> tldcd:mode_sense: Device geometry: NumDrives = 1 at address 32 12:32:00.057 [21855] <3> tldcd:mode_sense: --> NumSlots = 8 at address 256 12:32:00.057 [21855] <3> tldcd:mode_sense: --> NumTransports = 1 at address 0 12:32:00.057 [21855] <3> tldcd:mode_sense: --> NumIE = 0 at address 0 12:32:00.059 [21855] <6> tldcd:inquiry: Read device table for DELL PV-124T 0090, type 8, slots 8 and ie 0 12:32:00.059 [21855] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:974> search robot list (length=388) for DELL PV-124T, type 8 12:32:00.059 [21855] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:1227> found match: "DELL PV-124T" DELL PV-124T 12:32:00.059 [21855] <5> tldcd:inquiry: inquiry() function processing library DELL PV-124T 0090: 12:32:00.059 [21855] <6> tldcd:listen_loop: accept: newfd = -1, error = 4, timersig = 1 12:32:00.060 [21855] <6> tldcd:listen_loop: tldcd.c.2691, newfd = INVALID_SOCKET, newfd=-1, timersig=1, error=4, EINTR=4, selectret=-1 12:32:03.324 [21813] <4> AddTldLtiReqEntry: Processing ROBOT_DISMOUNT request... 12:32:03.324 [21813] <2> ProcessTable: found active request id -1. Child pid = 0 flags = 0 request type 3 12:32:03.324 [21813] <5> DismountTape: TLD(0) DismountTape ****** from drive 1 12:32:03.324 [21813] <2> SpawnChild: TLD(0) Creating Child Process 12:32:03.325 [22201] <2> parse_tape_path: ltid request: /dev/nst0 , shared memory: /dev/nst0 12:32:03.325 [22201] <4> io_open: Drive Path = /dev/nst0 12:32:04.333 [22201] <4> io_open: sense_key = 0x02, asc = 0x04, ascq = 0x02; retry = 0, errno = 0 12:32:04.333 [22201] <5> io_open: TLD(0) unload==TRUE, but no unload, drive 1 (device 0) 12:32:04.333 [22201] <4> robotd_connect: connect to host his1 service tldcd automatically 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1046: host_cache_size: 200 0x000000c8 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1047: cache_time: 3600 0x00000e10 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1061: host_failed_cache_size: 40 0x00000028 12:32:04.334 [22201] <2> init_cache: ../../libvlibs/vnet_hosts.c.1062: cache_time: 3600 0x00000e10 12:32:04.338 [22201] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4052: connect in progress: 0 0x00000000 12:32:04.339 [22201] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2054: VN_REQUEST_SERVICE_SOCKET: 6 0x00000006 12:32:04.339 [22201] <2> vnet_vnetd_service_socket: ../../libvlibs/vnet_vnetd.c.2068: service: tldcd 12:32:04.379 [21855] <6> tldcd:listen_loop: select: selectret = 1, error = 0, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:32:04.379 [21855] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1257: hash_str1: NULL 12:32:04.379 [21855] <2> vnet_receive_network_socket: ../../libvlibs/vnet_vnetd.c.1258: hash_str2: 12:32:04.379 [21855] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1771: hash_str1: NULL 12:32:04.379 [21855] <2> verify_hashes: ../../libvlibs/vnet_vnetd.c.1773: hash_str2: 12:32:04.379 [21855] <2> mm_accept_daemon_socket: netconf.c.1735: accept()ed vnetd connection from 192.168.1.13 12:32:04.379 [22201] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4238: in progress connect: 0 0x00000000 12:32:04.379 [21855] <6> tldcd:listen_loop: accept: newfd = 13, error = 0, timersig = 0 12:32:04.379 [21855] <4> peer_hostname: Connection from host his1, 192.168.1.13, port 45384 12:32:04.379 [22201] <2> vnet_async_connect: ../../libvlibs/vnet_vnetd.c.4241: connect: async CONNECT FROM 192.168.1.13.45384 TO 192.168.1.13.13724 fd = 10 12:32:04.379 [22201] <2> tldd@VssSetLogLevel: ++++ ENTERING ++++ 12:32:04.379 [22201] <4> tldd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:32:04.379 [22201] <2> tldd@VssSetLogLevel: ---- EXITING ---- 12:32:04.379 [22201] <2> vnet_check_vxss_client_magic_with_info: ../../libvlibs/vnet_vxss_helper.c.878: Ignoring VxSS authentication: 2 0x00000002 12:32:04.379 [22201] <2> robotd_connect: Not using VxSS authentication. 12:32:04.379 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.494: VxSS magic: 789604432 0x2f106850 12:32:04.379 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.495: remote_vxss: 3 0x00000003 12:32:04.379 [21855] <2> tldcd@VssSetLogLevel: ++++ ENTERING ++++ 12:32:04.379 [21855] <4> tldcd@VssSetLogLevel: (../../libVnbat/vss_auth.cpp,4798): Setting VSSLOG level to 5. 12:32:04.379 [21855] <2> tldcd@VssSetLogLevel: ---- EXITING ---- 12:32:04.379 [21855] <2> vnet_check_vxss_server_magic: ../../libvlibs/vnet_vxss_helper.c.537: Ignoring VxSS authentication: 2 0x00000002 12:32:04.379 [21855] <2> robotd_check_magic: Not using VxSS authentication. 12:32:04.379 [21855] <6> tldcd:process_request: tldcd.c.3062, process_request(), received command=3, from peername=his1, version 50 12:32:04.380 [21855] <5> tldcd:mount_unmount_drive: Processing UNMOUNT, TLD(0) drive 1, slot -1, barcode , vsn ****** 12:32:04.380 [22204] <5> tldcd:command_init: TLD(0) opening robotic path /dev/sg3 12:32:04.490 [22204] <3> tldcd:mode_sense: Device geometry: NumDrives = 1 at address 32 12:32:04.490 [22204] <3> tldcd:mode_sense: --> NumSlots = 8 at address 256 12:32:04.490 [22204] <3> tldcd:mode_sense: --> NumTransports = 1 at address 0 12:32:04.490 [22204] <3> tldcd:mode_sense: --> NumIE = 0 at address 0 12:32:04.493 [22204] <6> tldcd:inquiry: Read device table for DELL PV-124T 0090, type 8, slots 8 and ie 0 12:32:04.493 [22204] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:974> search robot list (length=388) for DELL PV-124T, type 8 12:32:04.493 [22204] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:1227> found match: "DELL PV-124T" DELL PV-124T 12:32:04.493 [22204] <5> tldcd:inquiry: inquiry() function processing library DELL PV-124T 0090: 12:32:04.493 [22204] <6> tldcd:read_element_status_drive: RES drive 1 12:32:04.656 [22204] <3> tldcd:tape_in_drive: address = 32, source address = 0, source valid = 0 12:32:04.656 [22204] <3> -->: sense = 00, qual = 00, except = 0, access = 1, full = 1, invert = 0 12:32:04.656 [22204] <3> -->: voltag = , [00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 ] 12:32:04.656 [22204] <6> tldcd:tape_in_drive: valid = 0, sel = 0, barcode = () 12:32:04.656 [22204] <6> tldcd:read_element_status_slot: RES storage element 5 12:32:04.836 [22204] <3> tldcd:empty_slot: address = 260, source address = 1025, source valid = 0 12:32:04.836 [22204] <3> -->: sense = 00, qual = 00, except = 0, access = 1, full = 1, invert = 0 12:32:04.836 [22204] <3> -->: voltag = 000195L3 12:32:04.836 [22204] <3> -->: voltag hex: [30 30 30 31 39 35 4C 33 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 20 00 00 00 00 ] 12:32:04.837 [22204] <3> tldcd:move_medium: TLD(0) cannot dismount drive 1, slot 5 already is full 12:32:04.837 [22204] <5> tldcd:tld_main: TLD(0) closing/unlocking robotic path 12:32:04.842 [21855] <6> decode_response: sending response "EXIT_STATUS 6 0" for request on drive 1 (child_pid 22204) 12:32:04.842 [22201] <16> TldChildExit: Child process terminated abnormally with error 6 12:32:04.842 [21855] <6> tldcd:listen_loop: select: selectret = -1, error = 4, timersig = 0, auto_vmupdate = 0, timetosleep = -1 12:32:04.842 [21855] <6> tldcd:check_unit_attention: command_init to check for UA on robot 0 12:32:04.846 [21813] <5> GetResponseStatus: DecodeDismount: TLD(0) drive 1, Actual status: Robotic dismount failure 12:32:04.953 [21855] <3> tldcd:mode_sense: Device geometry: NumDrives = 1 at address 32 12:32:04.953 [21855] <3> tldcd:mode_sense: --> NumSlots = 8 at address 256 12:32:04.953 [21855] <3> tldcd:mode_sense: --> NumTransports = 1 at address 0 12:32:04.953 [21855] <3> tldcd:mode_sense: --> NumIE = 0 at address 0 12:32:04.955 [21855] <6> tldcd:inquiry: Read device table for DELL PV-124T 0090, type 8, slots 8 and ie 0 12:32:04.955 [21855] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:974> search robot list (length=388) for DELL PV-124T, type 8 12:32:04.955 [21855] <4> MmDeviceMappings::GetRobotAttributes : <../../lib/MmDeviceMappings.cpp:1227> found match: "DELL PV-124T" DELL PV-124T 12:32:04.955 [21855] <5> tldcd:inquiry: inquiry() function processing library DELL PV-124T 0090: 12:32:04.955 [21855] <6> tldcd:listen_loop: accept: newfd = -1, error = 4, timersig = 1 12:32:04.955 [21855] <6> tldcd:listen_loop: tldcd.c.2691, newfd = INVALID_SOCKET, newfd=-1, timersig=1, error=4, EINTR=4, selectret=-1