Closed cconstab closed 4 weeks ago
more logs on npt
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp> ./npt -f "@cconstab" -t "@ssh_1" -r "@stream" -d orac -l 9000 -p 445 -h 192.168.1.90 -T 180m -K
Connecting ... Connected
2024-07-03 19:05:42.677954 : Sending daemon feature check request
2024-07-03 19:05:42.678953 : Fetching host and port from srvd
2024-07-03 19:05:46.821738 : Received host and port from srvd
2024-07-03 19:05:46.821738 : Waiting for daemon feature check response
2024-07-03 19:05:47.007355 : Received daemon feature check response
2024-07-03 19:05:47.020357 : Required daemon features are supported
2024-07-03 19:05:47.165439 : Sending session request to the device daemon
2024-07-03 19:05:47.772314 : Waiting for response from the device daemon
2024-07-03 19:05:48.855341 : Received response from the device daemon
2024-07-03 19:05:48.858342 : Will use local port 9000
2024-07-03 19:05:48.858342 : Creating connection to socket rendezvous
2024-07-03 19:05:48.881866 : npt is listening on localhost:9000
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp> ./npt -f "@cconstab" -t "@ssh_1" -r "@stream" -d orac -l 9000 -p 445 -h 192.168.1.90 -T 180m -K -v
Connecting ... INFO|2024-07-03 19:12:45.594148|AtLookup|Creating new connection
INFO|2024-07-03 19:12:46.199348|AtLookup|New connection created OK
INFO|2024-07-03 19:12:46.443255|AtLookup|auth success
INFO|2024-07-03 19:12:46.443255|AtClientManager|setCurrentAtSign called with atSign @cconstab
INFO|2024-07-03 19:12:46.443255|AtClientManager|Switching atSigns from null to @cconstab
INFO|2024-07-03 19:12:46.446257|HiveBase|commit_log_cba5ddc06a0eefc37444407e71b982f81edd408097efe7c65e51762a7ba5ddfa initialized successfully
INFO|2024-07-03 19:12:46.448257|HiveBase|cba5ddc06a0eefc37444407e71b982f81edd408097efe7c65e51762a7ba5ddfa initialized successfully
INFO|2024-07-03 19:12:46.449255|AtClientCommitLogCompaction (@cconstab)|Starting commit log compaction job running for every 11 minute(s)
INFO|2024-07-03 19:12:46.449255|AtClientManager|setCurrentAtSign complete
Connected
INFO|2024-07-03 19:12:46.452257| Npt |Initializing _NptImpl
INFO|2024-07-03 19:12:46.452257| SshnpdChannel |Starting monitor for notifications with regex: "ec7055d0-a49c-4213-b14b-f657759ef49b.orac.sshnp@ssh_1"
INFO|2024-07-03 19:12:46.452257| SshnpdChannel |Subscribing to notifications with regex: "ec7055d0-a49c-4213-b14b-f657759ef49b.orac.sshnp@ssh_1"
INFO|2024-07-03 19:12:46.452257| Npt |Sending daemon feature check request
INFO|2024-07-03 19:12:46.452257| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 19:12:46.453253| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 19:12:46.453253| SrvdChannel |Subscribing to notifications with regex: "ec7055d0-a49c-4213-b14b-f657759ef49b.sshrvd@"
INFO|2024-07-03 19:12:46.453253| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 19:12:46.453253| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"ec7055d0-a49c-4213-b14b-f657759ef49b","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T19:12:46.452257"}
INFO|2024-07-03 19:12:46.832850|AbstractAtKeyEncryption (@cconstab)|Encrypted shared symmetric key for @cconstab not found in local storage
INFO|2024-07-03 19:12:46.832850|AbstractAtKeyEncryption (@cconstab)|Deleting @ssh_1:shared_key@cconstab from LocalSecondary
INFO|2024-07-03 19:12:46.832850|AbstractAtKeyEncryption (@cconstab)|Fetching shared symmetric key for @cconstab from atServer
INFO|2024-07-03 19:12:46.832850|AtLookup|Creating new connection
INFO|2024-07-03 19:12:46.842852|AbstractAtKeyEncryption (@cconstab)|Encrypted shared symmetric key for @cconstab not found in local storage
INFO|2024-07-03 19:12:46.842852|AbstractAtKeyEncryption (@cconstab)|Deleting @stream:shared_key@cconstab from LocalSecondary
INFO|2024-07-03 19:12:46.842852|AbstractAtKeyEncryption (@cconstab)|Fetching shared symmetric key for @cconstab from atServer
INFO|2024-07-03 19:12:47.035450|AtLookup|New connection created OK
INFO|2024-07-03 19:12:47.411790|Monitor (@cconstab)|monitor started for @cconstab with last notification time: null
INFO|2024-07-03 19:12:47.411790|AtLookup|auth success
INFO|2024-07-03 19:12:47.533303|AbstractAtKeyEncryption (@cconstab)|Retrieved my encrypted copy of shared symmetric key for @ssh_1 from atServer - saving to local storage
INFO|2024-07-03 19:12:47.536306|AbstractAtKeyEncryption (@cconstab)|'Their' copy of shared symmetric key for @ssh_1 not found in local storage - will check atServer
INFO|2024-07-03 19:12:47.640383|AbstractAtKeyEncryption (@cconstab)|Retrieved my encrypted copy of shared symmetric key for @stream from atServer - saving to local storage
INFO|2024-07-03 19:12:47.643382|AbstractAtKeyEncryption (@cconstab)|'Their' copy of shared symmetric key for @stream not found in local storage - will check atServer
INFO|2024-07-03 19:12:47.734555|AbstractAtKeyEncryption (@cconstab)|Found 'their' copy of shared symmetric key for @ssh_1 in atServer - saving to local storage
INFO|2024-07-03 19:12:47.843648|AbstractAtKeyEncryption (@cconstab)|Found 'their' copy of shared symmetric key for @stream in atServer - saving to local storage
INFO|2024-07-03 19:12:49.272738| SrvdChannel |Received from srvd: 85.239.53.170,35593,33141,2024-07-04T02:12:50.059926
INFO|2024-07-03 19:12:49.272738| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:35593:33141 rvdNonce: 2024-07-04T02:12:50.059926
INFO|2024-07-03 19:12:49.272738| SrvdChannel |Daemon will connect to: 85.239.53.170:33141
INFO|2024-07-03 19:12:49.379834| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 19:12:49.379834| SshnpdChannel |Completing the future
INFO|2024-07-03 19:12:49.393342| Npt |Sending session request to the device daemon
INFO|2024-07-03 19:12:50.687169| SshnpdChannel |Received ec7055d0-a49c-4213-b14b-f657759ef49b.orac.sshnp@ssh_1 notification
INFO|2024-07-03 19:12:50.902680| SshnpdChannel |Signing Verification Result: ResultType: bool, Result: true, SigningMetadata: {HashingAlgo: sha256, SigningAlgo: rsa2048, SignatureTimestamp: 2024-07-04 02:12:50.902680Z}
INFO|2024-07-03 19:12:50.903679| SshnpdChannel |svr.result is a bool
INFO|2024-07-03 19:12:50.903679| SshnpdChannel |svr.result is true
INFO|2024-07-03 19:12:50.903679| SshnpdChannel |Verified signature of msg from @ssh_1
INFO|2024-07-03 19:12:50.903679| SshnpdChannel |Received ephemeralPrivateKey: null
INFO|2024-07-03 19:12:50.903679| SshnpdChannel |Received encrypted sessionAESKey: EfsXTn5DYjsTCpCOMTx+0nBolL1YZw3XSkO9M/bW9qzOEmuz1Hyek0o/z/ZFgI4MHW3emeP7ZqbEKbJmm3ZurxbqFel7Lh2vo8RteqO4/8/kEMdj2jv7HOcvKuBeKRL6TV33RmP194V5iGR8depRIG30WfLBHOVuUjHIemIbg+mz6BpOrASwRagkWXySHlymn4wl7V+VN/8kvZ5+xR/tq9dvC1cdMXiEbBX+anNRcbBNDlrBrS1XoBgrePDNAdoqQU7qnfBbyLMf+h8clU1ANPHDeUEwHG8/G9hlmIxTLO6lt+XbaCDkf3zJ/J+zsOhYijaJc0Y1q4vY812n5VQL+A==
INFO|2024-07-03 19:12:50.903679| SshnpdChannel |Received encrypted sessionIV: f55j3J1cbBiYoIy7XDcAeIH94+bxHxP+2F7jIOGYq8PyyOCCshYaVB5063YNpBJ7UzlBALEfm0iPD32GXOLyR4p5hdCYlzEHzCpBNNo48+zTRhPNYwkgm7vTGUzVDwukmPPe3nNiPi+3wNFJbqghPFtNiKHLRhutFWBEeicc+sIxeM/khFgQ/y/ZSB8NvlryWtSBNHTwgeFUcxLR+S69aRZBvolttzotLXagwLEXDr8/ZoBTjuRVTjWyxjFU+U3lZ1akuIYVRz93aF4siTFDUgOTTv1aepzf+DjRym6ou5GTFkBVok5glz2yOqLYr0qkLgbJdMvywsPgfoZRj2wNKA==
INFO|2024-07-03 19:12:50.908680| SshnpdChannel |Session ec7055d0-a49c-4213-b14b-f657759ef49b connected successfully
INFO|2024-07-03 19:12:50.979275| SshnpdChannel |sshnpdAck: SshnpdAck.acknowledged
INFO|2024-07-03 19:12:50.981275| SrvImplDart |New SrvImplDart - localPort 9000
INFO|2024-07-03 19:12:51.003790| SrvImplDart |_runClientSideMulti authenticating control socket connection to rvd
INFO|2024-07-03 19:12:51.004793| SrvImplDart |_runClientSideMulti calling SocketConnector.serverToSocket
INFO|2024-07-03 19:12:51.004793| SrvImplDart |_runClientSideMulti serverToSocket is ready
INFO|2024-07-03 19:12:52.263701| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 19:12:52.263701| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp>
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp>
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp>
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp> ./npt -f "@cconstab" -t "@ssh_1" -r "@stream" -d orac -l 9000 -p 445 -h 192.168.1.90 -T 180m -K -v
Connecting ... INFO|2024-07-03 19:13:50.132169|AtLookup|Creating new connection
INFO|2024-07-03 19:13:50.748932|AtLookup|New connection created OK
INFO|2024-07-03 19:13:50.990979|AtLookup|auth success
INFO|2024-07-03 19:13:50.990979|AtClientManager|setCurrentAtSign called with atSign @cconstab
INFO|2024-07-03 19:13:50.990979|AtClientManager|Switching atSigns from null to @cconstab
INFO|2024-07-03 19:13:51.003883|HiveBase|commit_log_cba5ddc06a0eefc37444407e71b982f81edd408097efe7c65e51762a7ba5ddfa initialized successfully
INFO|2024-07-03 19:13:51.005883|HiveBase|cba5ddc06a0eefc37444407e71b982f81edd408097efe7c65e51762a7ba5ddfa initialized successfully
INFO|2024-07-03 19:13:51.006882|AtClientCommitLogCompaction (@cconstab)|Starting commit log compaction job running for every 11 minute(s)
INFO|2024-07-03 19:13:51.006882|AtClientManager|setCurrentAtSign complete
Connected
INFO|2024-07-03 19:13:51.009881| Npt |Initializing _NptImpl
INFO|2024-07-03 19:13:51.009881| SshnpdChannel |Starting monitor for notifications with regex: "5bc25206-fa39-4e10-8d42-8808b8e6b0d9.orac.sshnp@ssh_1"
INFO|2024-07-03 19:13:51.009881| SshnpdChannel |Subscribing to notifications with regex: "5bc25206-fa39-4e10-8d42-8808b8e6b0d9.orac.sshnp@ssh_1"
INFO|2024-07-03 19:13:51.009881| Npt |Sending daemon feature check request
INFO|2024-07-03 19:13:51.009881| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 19:13:51.009881| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 19:13:51.010884| SrvdChannel |Subscribing to notifications with regex: "5bc25206-fa39-4e10-8d42-8808b8e6b0d9.sshrvd@"
INFO|2024-07-03 19:13:51.010884| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 19:13:51.010884| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"5bc25206-fa39-4e10-8d42-8808b8e6b0d9","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T19:13:51.009881"}
INFO|2024-07-03 19:13:51.399195|AbstractAtKeyEncryption (@cconstab)|Encrypted shared symmetric key for @cconstab not found in local storage
INFO|2024-07-03 19:13:51.399195|AbstractAtKeyEncryption (@cconstab)|Deleting @stream:shared_key@cconstab from LocalSecondary
INFO|2024-07-03 19:13:51.400199|AbstractAtKeyEncryption (@cconstab)|Fetching shared symmetric key for @cconstab from atServer
INFO|2024-07-03 19:13:51.400199|AtLookup|Creating new connection
INFO|2024-07-03 19:13:51.409198|AbstractAtKeyEncryption (@cconstab)|Encrypted shared symmetric key for @cconstab not found in local storage
INFO|2024-07-03 19:13:51.410196|AbstractAtKeyEncryption (@cconstab)|Deleting @ssh_1:shared_key@cconstab from LocalSecondary
INFO|2024-07-03 19:13:51.410196|AbstractAtKeyEncryption (@cconstab)|Fetching shared symmetric key for @cconstab from atServer
INFO|2024-07-03 19:13:51.851622|Monitor (@cconstab)|monitor started for @cconstab with last notification time: null
INFO|2024-07-03 19:13:51.991309|AtLookup|New connection created OK
INFO|2024-07-03 19:13:52.216863|AtLookup|auth success
INFO|2024-07-03 19:13:52.325375|AbstractAtKeyEncryption (@cconstab)|Retrieved my encrypted copy of shared symmetric key for @stream from atServer - saving to local storage
INFO|2024-07-03 19:13:52.327823|AbstractAtKeyEncryption (@cconstab)|'Their' copy of shared symmetric key for @stream not found in local storage - will check atServer
INFO|2024-07-03 19:13:52.418387|AbstractAtKeyEncryption (@cconstab)|Retrieved my encrypted copy of shared symmetric key for @ssh_1 from atServer - saving to local storage
INFO|2024-07-03 19:13:52.421390|AbstractAtKeyEncryption (@cconstab)|'Their' copy of shared symmetric key for @ssh_1 not found in local storage - will check atServer
INFO|2024-07-03 19:13:52.526534|AbstractAtKeyEncryption (@cconstab)|Found 'their' copy of shared symmetric key for @stream in atServer - saving to local storage
INFO|2024-07-03 19:13:52.634611|AbstractAtKeyEncryption (@cconstab)|Found 'their' copy of shared symmetric key for @ssh_1 in atServer - saving to local storage
INFO|2024-07-03 19:13:53.446653| SrvdChannel |Received from srvd: 85.239.53.170,42027,38909,2024-07-04T02:13:54.718060
INFO|2024-07-03 19:13:53.446653| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:42027:38909 rvdNonce: 2024-07-04T02:13:54.718060
INFO|2024-07-03 19:13:53.446653| SrvdChannel |Daemon will connect to: 85.239.53.170:38909
INFO|2024-07-03 19:13:53.661918| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 19:13:53.661918| SshnpdChannel |Completing the future
INFO|2024-07-03 19:13:53.674918| Npt |Sending session request to the device daemon
INFO|2024-07-03 19:13:55.262380| SshnpdChannel |Received 5bc25206-fa39-4e10-8d42-8808b8e6b0d9.orac.sshnp@ssh_1 notification
INFO|2024-07-03 19:13:55.369305| SshnpdChannel |Signing Verification Result: ResultType: bool, Result: true, SigningMetadata: {HashingAlgo: sha256, SigningAlgo: rsa2048, SignatureTimestamp: 2024-07-04 02:13:55.369305Z}
INFO|2024-07-03 19:13:55.369305| SshnpdChannel |svr.result is a bool
INFO|2024-07-03 19:13:55.369305| SshnpdChannel |svr.result is true
INFO|2024-07-03 19:13:55.369305| SshnpdChannel |Verified signature of msg from @ssh_1
INFO|2024-07-03 19:13:55.370303| SshnpdChannel |Received ephemeralPrivateKey: null
INFO|2024-07-03 19:13:55.370303| SshnpdChannel |Received encrypted sessionAESKey: HDxLpqY8IvxD8cCELaZs3yXrQmV4c9JjQULfvKkco5uAwep2VquvtyMjzozzltpNzSV7qSL4hGtYjuae1IT+pbf8iq0MEjEkACNG7UZxqSLHLlJV3k3ZSNKXVJ+vj2+/xxksjZcLTszpXODY+JxeqijM5qA+hNiO38tPtqQJW0LUMyOfwpraRPnPV8Tw7KBhsNPTSw6LbHF2KIdjBxh/64F3TlzWFhiKl/bF7w4wRHoAS/ZN4mGYu7pbzkAz2mbJojMGOXYfjdtjXrAe1DywW9CEyp8IbOzVUMROvtCn4O9bxAXK+2yKRgqkK4Krm5FoUQoSIULVKZza7KdEIXRx+Q==
INFO|2024-07-03 19:13:55.370303| SshnpdChannel |Received encrypted sessionIV: UvHhFlxq+Tiy8jDC9oN5U+zurnQ/cfmrjIDTAi3NIz7pwS6xpPlKgY0Hutwje5hqgclW6F+VhuS+XE0VNpXgO64HIH9HeDkmzJk+ZDz57o03CK7WjNa6iXLj5SOXdARez5DzFMCRTpB4ZuFnE8A23k196VBfHCwF1SSdUVbLaCO8GPSZ5duWfKrz3Ks3GrAfH++qLEA2SXDIG2K9W/2F84O1z6+u760+vrCLmEgQIH3FqmMeyFVZILYEnrR8wKER//pCU1oKs1Nez5U22apEogwogIpmplK95Z/T2BJksfc87RjtrGgecmTUH647LpNh2f5TBXDuaJ8X4aFwAsyJfg==
INFO|2024-07-03 19:13:55.374300| SshnpdChannel |Session 5bc25206-fa39-4e10-8d42-8808b8e6b0d9 connected successfully
INFO|2024-07-03 19:13:55.490335| SshnpdChannel |sshnpdAck: SshnpdAck.acknowledged
INFO|2024-07-03 19:13:55.492342| SrvImplDart |New SrvImplDart - localPort 9000
INFO|2024-07-03 19:13:55.521921| SrvImplDart |_runClientSideMulti authenticating control socket connection to rvd
INFO|2024-07-03 19:13:55.521921| SrvImplDart |_runClientSideMulti calling SocketConnector.serverToSocket
INFO|2024-07-03 19:13:55.522921| SrvImplDart |_runClientSideMulti serverToSocket is ready
INFO|2024-07-03 19:13:55.622466| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 19:13:55.622466| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 19:24:00.029011|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 19:24:09.949086|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 19:23:59.937846
SEVERE|2024-07-03 19:24:09.949086|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 19:23:59.937846
SEVERE|2024-07-03 19:24:09.949086|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 19:23:59.937846
INFO|2024-07-03 19:24:09.949086|AtLookup|Creating new connection
INFO|2024-07-03 19:24:10.154709|AtLookup|New connection created OK
INFO|2024-07-03 19:24:10.396420|AtLookup|auth success
INFO|2024-07-03 19:33:47.643028|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 19:33:57.556035|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 19:33:47.549296
SEVERE|2024-07-03 19:33:57.556035|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 19:33:47.549296
SEVERE|2024-07-03 19:33:57.556035|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 19:33:47.549296
INFO|2024-07-03 19:33:57.556035|AtLookup|Creating new connection
INFO|2024-07-03 19:33:57.757639|AtLookup|New connection created OK
INFO|2024-07-03 19:33:57.975234|AtLookup|auth success
INFO|2024-07-03 19:41:30.899543|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 19:41:40.799205|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 19:41:30.798029
SEVERE|2024-07-03 19:41:40.799205|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 19:41:30.798029
SEVERE|2024-07-03 19:41:40.799205|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 19:41:30.798029
INFO|2024-07-03 19:41:40.799711|AtLookup|Creating new connection
INFO|2024-07-03 19:41:40.999485|AtLookup|New connection created OK
INFO|2024-07-03 19:41:41.296137|AtLookup|auth success
INFO|2024-07-03 19:43:49.245836| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 19:43:49.246835| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 19:43:49.274832| SrvImplDart |_runClientSideMulti controlSocket done
INFO|2024-07-03 19:43:49.274832| SrvImplDart |_runClientSideMulti sc.done
INFO|2024-07-03 19:43:54.285272| Npt |Initializing _NptImpl
INFO|2024-07-03 19:43:54.285272| SshnpdChannel |Starting monitor for notifications with regex: "8e5ea97f-de24-4854-a33d-1bd1ee9c9afa.orac.sshnp@ssh_1"
INFO|2024-07-03 19:43:54.285272| SshnpdChannel |Subscribing to notifications with regex: "8e5ea97f-de24-4854-a33d-1bd1ee9c9afa.orac.sshnp@ssh_1"
INFO|2024-07-03 19:43:54.285272| Npt |Sending daemon feature check request
INFO|2024-07-03 19:43:54.285778| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 19:43:54.285778| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 19:43:54.285778| SrvdChannel |Subscribing to notifications with regex: "8e5ea97f-de24-4854-a33d-1bd1ee9c9afa.sshrvd@"
INFO|2024-07-03 19:43:54.285778| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 19:43:54.285778| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"8e5ea97f-de24-4854-a33d-1bd1ee9c9afa","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T19:43:54.285272"}
INFO|2024-07-03 19:43:55.560609| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 19:43:55.560609| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 19:43:55.561609| SshnpdChannel |Completing the future
INFO|2024-07-03 19:43:56.911323| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 19:43:56.975395| SrvdChannel |Received from srvd: 85.239.53.170,33423,38137,2024-07-04T02:43:58.117243
INFO|2024-07-03 19:43:56.976395| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:33423:38137 rvdNonce: 2024-07-04T02:43:58.117243
INFO|2024-07-03 19:43:56.976395| SrvdChannel |Daemon will connect to: 85.239.53.170:38137
INFO|2024-07-03 19:43:57.037398| Npt |Sending session request to the device daemon
INFO|2024-07-03 19:43:58.964595| SshnpdChannel |Received 8e5ea97f-de24-4854-a33d-1bd1ee9c9afa.orac.sshnp@ssh_1 notification
INFO|2024-07-03 19:43:58.965595| SshnpdChannel |Signing Verification Result: ResultType: bool, Result: true, SigningMetadata: {HashingAlgo: sha256, SigningAlgo: rsa2048, SignatureTimestamp: 2024-07-04 02:43:58.965595Z}
INFO|2024-07-03 19:43:58.965595| SshnpdChannel |svr.result is a bool
INFO|2024-07-03 19:43:58.965595| SshnpdChannel |svr.result is true
INFO|2024-07-03 19:43:58.966597| SshnpdChannel |Verified signature of msg from @ssh_1
INFO|2024-07-03 19:43:58.966597| SshnpdChannel |Received ephemeralPrivateKey: null
INFO|2024-07-03 19:43:58.966597| SshnpdChannel |Received encrypted sessionAESKey: iDSovk6dtEOhQt659S1IrNJBJGCodYjE8oyr6xclNbXUYgrqLVPh0Gg9cYKRBm2ByJqOrpZtPaN36KjqacFaHXJmBfT/9auCET7BVqpHJ0klAaUgc2m6SVLAHX9LhrHa8WjqyRqL8Ddd4SjDbCsrvDc1urZVzMWtcySl2fWrItKy6W2Rpt0NGf8eZywpPwzLOjNLI//CeNvTgxuLOsSoZtfo7pkzqPcpxvLUXeEFB0Rmqm1MZO3monEBusdVzZhYE4Q+PRfs8F6q7Lxcfmapf50RIerBRmduv0gM1DcRUBx60JHLuvnP2Kd0IzR/L/yHHK59wNWQDSMjpZnO0x1bzg==
INFO|2024-07-03 19:43:58.966597| SshnpdChannel |Received encrypted sessionIV: iuy1Tc2HMyWCiXaCw+Zkhyv9M5UCQ34JEnJx4uBUPOq+r4RdRWeVWhqYwivtZAFEge1qhoj9Sak+YxyW17UuLiIgmf8SPsEVvo/M9oqhLzyD1ZRXWlkkxUnYc8UgKCPTXG2/n7BSoSmo6n/WTc6yP1nI/9RnaLXBfAoqBE/gkEXwvD72b2OEQqKURPlSptqzYT1xXswJiZRmbeqKsrW8nltznu7hwYOeZzOf1rP24iwRwxeNUlFzN+jVlFa+A5fI3pTssFD119jX78WA1nj9dwp+7jDKoqGsqvJRywshMmTwfYCk5X9T/NnuCeb+4BVwXdT9KduqmijUDtE043kkzg==
INFO|2024-07-03 19:43:58.971595| SshnpdChannel |Session 8e5ea97f-de24-4854-a33d-1bd1ee9c9afa connected successfully
INFO|2024-07-03 19:43:59.087677| SshnpdChannel |sshnpdAck: SshnpdAck.acknowledged
INFO|2024-07-03 19:43:59.089679| SrvImplDart |New SrvImplDart - localPort 9000
INFO|2024-07-03 19:43:59.118221| SrvImplDart |_runClientSideMulti authenticating control socket connection to rvd
INFO|2024-07-03 19:43:59.118221| SrvImplDart |_runClientSideMulti calling SocketConnector.serverToSocket
INFO|2024-07-03 19:43:59.119222| SrvImplDart |_runClientSideMulti serverToSocket is ready
INFO|2024-07-03 19:44:56.802675| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 19:44:56.802675| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 19:51:50.878482|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 19:52:00.779114|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 19:51:50.765830
SEVERE|2024-07-03 19:52:00.779114|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 19:51:50.765830
SEVERE|2024-07-03 19:52:00.779114|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 19:51:50.765830
INFO|2024-07-03 19:52:00.779114|AtLookup|Creating new connection
INFO|2024-07-03 19:52:00.985104|AtLookup|New connection created OK
INFO|2024-07-03 19:52:01.225377|AtLookup|auth success
INFO|2024-07-03 20:00:18.441821|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 20:00:28.357982|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 20:00:18.343446
SEVERE|2024-07-03 20:00:28.357982|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 20:00:18.343446
SEVERE|2024-07-03 20:00:28.357982|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 20:00:18.343446
INFO|2024-07-03 20:00:28.357982|AtLookup|Creating new connection
INFO|2024-07-03 20:00:28.571590|AtLookup|New connection created OK
INFO|2024-07-03 20:00:28.811173|AtLookup|auth success
INFO|2024-07-03 20:18:37.492536|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
INFO|2024-07-03 20:18:37.492536|AtLookup|Creating new connection
INFO|2024-07-03 20:18:38.251834|AtLookup|New connection created OK
INFO|2024-07-03 20:18:38.499376|AtLookup|auth success
INFO|2024-07-03 20:24:00.650829|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 20:24:10.568900|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 20:24:00.562829
SEVERE|2024-07-03 20:24:10.568900|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 20:24:00.562829
SEVERE|2024-07-03 20:24:10.568900|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 20:24:00.562829
INFO|2024-07-03 20:24:10.568900|AtLookup|Creating new connection
INFO|2024-07-03 20:24:10.768505|AtLookup|New connection created OK
INFO|2024-07-03 20:24:11.018791|AtLookup|auth success
INFO|2024-07-03 20:32:05.692896| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 20:32:05.692896| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 20:32:05.716898| SrvImplDart |_runClientSideMulti controlSocket done
INFO|2024-07-03 20:32:05.716898| SrvImplDart |_runClientSideMulti sc.done
INFO|2024-07-03 20:32:10.723306| Npt |Initializing _NptImpl
INFO|2024-07-03 20:32:10.723306| SshnpdChannel |Starting monitor for notifications with regex: "a65ac8f4-cde7-4021-8114-2082e55d4bbf.orac.sshnp@ssh_1"
INFO|2024-07-03 20:32:10.723306| SshnpdChannel |Subscribing to notifications with regex: "a65ac8f4-cde7-4021-8114-2082e55d4bbf.orac.sshnp@ssh_1"
INFO|2024-07-03 20:32:10.723306| Npt |Sending daemon feature check request
INFO|2024-07-03 20:32:10.723306| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 20:32:10.723306| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 20:32:10.723306| SrvdChannel |Subscribing to notifications with regex: "a65ac8f4-cde7-4021-8114-2082e55d4bbf.sshrvd@"
INFO|2024-07-03 20:32:10.724305| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 20:32:10.724305| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"a65ac8f4-cde7-4021-8114-2082e55d4bbf","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T20:32:10.723306"}
INFO|2024-07-03 20:32:11.644802|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 20:32:21.557490|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 20:32:11.555689
SEVERE|2024-07-03 20:32:21.557490|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 20:32:11.555689
INFO|2024-07-03 20:32:21.557490| SrvdChannel |ERROR:id: 3893010d-40a7-4d0c-bc73-38afd99a8f7f status: NotificationStatusEnum.undelivered
INFO|2024-07-03 20:32:21.557490|AtLookup|Creating new connection
INFO|2024-07-03 20:32:21.748493|AtLookup|New connection created OK
INFO|2024-07-03 20:32:21.962929|AtLookup|auth success
INFO|2024-07-03 20:32:23.626545| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:24.255166| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:24.255166| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:24.255166| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:24.256168| SshnpdChannel |Completing the future
INFO|2024-07-03 20:32:25.802496| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:27.975663| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:30.147603| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:32.329218| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:34.497649| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:36.677658| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 20:32:37.878101| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 20:32:42.891203| Npt |Initializing _NptImpl
INFO|2024-07-03 20:32:42.891203| SshnpdChannel |Starting monitor for notifications with regex: "4fe54e18-ea0c-4146-b941-6941c2f926ac.orac.sshnp@ssh_1"
INFO|2024-07-03 20:32:42.891203| SshnpdChannel |Subscribing to notifications with regex: "4fe54e18-ea0c-4146-b941-6941c2f926ac.orac.sshnp@ssh_1"
INFO|2024-07-03 20:32:42.891203| Npt |Sending daemon feature check request
INFO|2024-07-03 20:32:42.891203| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 20:32:42.891203| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 20:32:42.891203| SrvdChannel |Subscribing to notifications with regex: "4fe54e18-ea0c-4146-b941-6941c2f926ac.sshrvd@"
INFO|2024-07-03 20:32:42.892204| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 20:32:42.892204| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"4fe54e18-ea0c-4146-b941-6941c2f926ac","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T20:32:42.891203"}
INFO|2024-07-03 20:32:43.720965| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:43.720965| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:43.721963| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:43.721963| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:32:43.721963| SshnpdChannel |Completing the future
INFO|2024-07-03 20:32:45.403807| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:32:45.715485| SrvdChannel |Received from srvd: 85.239.53.170,35585,36253,2024-07-04T03:32:45.875788
INFO|2024-07-03 20:32:45.715485| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:35585:36253 rvdNonce: 2024-07-04T03:32:45.875788
INFO|2024-07-03 20:32:45.715485| SrvdChannel |Daemon will connect to: 85.239.53.170:36253
INFO|2024-07-03 20:32:45.745001| Npt |Sending session request to the device daemon
INFO|2024-07-03 20:32:47.451819| SshnpdChannel |Received 4fe54e18-ea0c-4146-b941-6941c2f926ac.orac.sshnp@ssh_1 notification
INFO|2024-07-03 20:32:47.452818| SshnpdChannel |Signing Verification Result: ResultType: bool, Result: true, SigningMetadata: {HashingAlgo: sha256, SigningAlgo: rsa2048, SignatureTimestamp: 2024-07-04 03:32:47.452818Z}
INFO|2024-07-03 20:32:47.452818| SshnpdChannel |svr.result is a bool
INFO|2024-07-03 20:32:47.452818| SshnpdChannel |svr.result is true
INFO|2024-07-03 20:32:47.452818| SshnpdChannel |Verified signature of msg from @ssh_1
INFO|2024-07-03 20:32:47.453816| SshnpdChannel |Received ephemeralPrivateKey: null
INFO|2024-07-03 20:32:47.453816| SshnpdChannel |Received encrypted sessionAESKey: JCmvGkKo+JgjnYfVddJ/WY9ugFxL4mI2e5V2CEXypksztMVB/P75kxN4tVX5wHE4IdIbXvSZF5yMZ5MOOSPYNTvI8SGUJ5ynq/N9mHR1RmaeLOPjpya8Lk9u1d3c0TovB9zoGTjUfdU7lavWG67aw+cZEuPXlJYQHGDytLau09mFQLUozRXRH3nzZCV+AO+Zi/Iwd4H4qFqCF2KpiV5uruwzftoyYAWwEHZ/K+WU2fVr4ntrDSigOOBjIbdWgKRbaqHY/eIckc6Uiu3qbwybGptQ6duRWKmbA6aVZVioTw8BWXAW93astcR6DDkt+oMBfmKYlogNzZ72Wm2AeJbATQ==
INFO|2024-07-03 20:32:47.453816| SshnpdChannel |Received encrypted sessionIV: AUYS0GZFIzoULebsbKVcvlVdj4be1Ud6TqltAP1k9c1qXuLKPjNBuSEtGY8eC/f61WU2YVQRWeaYO0j+a8GCYAJrxilkj5t2jLVHoDt2JAoZQTtlV3/31xiLl9hRyj9HxMrQOQbM+vRAjkJj5EtLrzH/tQdQGvZX3GV4Mv7MjPnVBvtBz1owl8GodDaSRFT1X7kApzB/eRsOsTW6FAXVRDZty9/h/DmG6y9kpmb1eKbFFbVvEya2B5Jbmgn38FwCREhmdsdC60kitAeIpr42uESSS4jBsORLkXq6VZkJBim5emSVHcrDemcwEFzcIfRk11EZ0MhjeK62/jRfLJaosQ==
INFO|2024-07-03 20:32:47.457817| SshnpdChannel |Session 4fe54e18-ea0c-4146-b941-6941c2f926ac connected successfully
INFO|2024-07-03 20:32:47.524819| SshnpdChannel |sshnpdAck: SshnpdAck.acknowledged
INFO|2024-07-03 20:32:47.526821| SrvImplDart |New SrvImplDart - localPort 9000
INFO|2024-07-03 20:32:47.557331| SrvImplDart |_runClientSideMulti authenticating control socket connection to rvd
INFO|2024-07-03 20:32:47.557331| SrvImplDart |_runClientSideMulti calling SocketConnector.serverToSocket
INFO|2024-07-03 20:32:47.558333| SrvImplDart |_runClientSideMulti serverToSocket is ready
INFO|2024-07-03 20:33:06.409161| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 20:33:06.410161| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 20:41:30.816245|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 20:41:40.737841|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 20:41:30.727731
SEVERE|2024-07-03 20:41:40.737841|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 20:41:30.727731
SEVERE|2024-07-03 20:41:40.737841|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 20:41:30.727731
INFO|2024-07-03 20:41:40.737841|AtLookup|Creating new connection
INFO|2024-07-03 20:41:40.934141|AtLookup|New connection created OK
INFO|2024-07-03 20:41:41.219814|AtLookup|auth success
INFO|2024-07-03 20:43:52.859980| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 20:43:52.859980| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 20:43:52.892105| SrvImplDart |_runClientSideMulti controlSocket done
INFO|2024-07-03 20:43:52.892105| SrvImplDart |_runClientSideMulti sc.done
INFO|2024-07-03 20:43:57.894926| Npt |Initializing _NptImpl
INFO|2024-07-03 20:43:57.894926| SshnpdChannel |Starting monitor for notifications with regex: "a7fe5f47-5d27-4903-a571-566e301cb24d.orac.sshnp@ssh_1"
INFO|2024-07-03 20:43:57.894926| SshnpdChannel |Subscribing to notifications with regex: "a7fe5f47-5d27-4903-a571-566e301cb24d.orac.sshnp@ssh_1"
INFO|2024-07-03 20:43:57.894926| Npt |Sending daemon feature check request
INFO|2024-07-03 20:43:57.894926| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 20:43:57.894926| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 20:43:57.895927| SrvdChannel |Subscribing to notifications with regex: "a7fe5f47-5d27-4903-a571-566e301cb24d.sshrvd@"
INFO|2024-07-03 20:43:57.895927| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 20:43:57.895927| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"a7fe5f47-5d27-4903-a571-566e301cb24d","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T20:43:57.894926"}
INFO|2024-07-03 20:43:59.094668| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:43:59.094668| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:43:59.094668| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:43:59.094668| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:43:59.094668| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 20:43:59.094668| SshnpdChannel |Completing the future
INFO|2024-07-03 20:44:00.478612| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 20:44:01.238851| SrvdChannel |Received from srvd: 85.239.53.170,33237,42601,2024-07-04T03:44:01.487526
INFO|2024-07-03 20:44:01.238851| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:33237:42601 rvdNonce: 2024-07-04T03:44:01.487526
INFO|2024-07-03 20:44:01.238851| SrvdChannel |Daemon will connect to: 85.239.53.170:42601
INFO|2024-07-03 20:44:01.359541| Npt |Sending session request to the device daemon
INFO|2024-07-03 20:44:03.375436| SshnpdChannel |Received a7fe5f47-5d27-4903-a571-566e301cb24d.orac.sshnp@ssh_1 notification
INFO|2024-07-03 20:44:03.376436| SshnpdChannel |Signing Verification Result: ResultType: bool, Result: true, SigningMetadata: {HashingAlgo: sha256, SigningAlgo: rsa2048, SignatureTimestamp: 2024-07-04 03:44:03.376436Z}
INFO|2024-07-03 20:44:03.376436| SshnpdChannel |svr.result is a bool
INFO|2024-07-03 20:44:03.376436| SshnpdChannel |svr.result is true
INFO|2024-07-03 20:44:03.376436| SshnpdChannel |Verified signature of msg from @ssh_1
INFO|2024-07-03 20:44:03.376436| SshnpdChannel |Received ephemeralPrivateKey: null
INFO|2024-07-03 20:44:03.376436| SshnpdChannel |Received encrypted sessionAESKey: c8ApPM6990grVk9pB5d6OCxr99X/V/TeIJRYe/RNshZUuqu+b654ofzfiBZDV7E7LeKH+ftfvYeUymn191PyYmDfccH2P6t0dZG1sPqB4sFcFlIoBrR44DI0o81Pepy+k09hPIeaztibpzYzY5OkDE86Gvh5mpBWJcOha87KzVp1Igg94tUkLc2ThUz4YbKYLS3TMoO4uz98VD46UGHuqm0uPCffGnAvbWMXRaPf1p7i+UqVrOI/3F/gcqZh/JXYOxmI8PMVl7ndlCj+w0kLymaBC9gSjVY4O+yWi4uaNkc7AI6CuAl4RT3ZVTCbgHzz+/qXNaak1FNoJwQCWSrhHw==
INFO|2024-07-03 20:44:03.377436| SshnpdChannel |Received encrypted sessionIV: aYurNI3It8EyTXFfvyJ4f5Pq2JIDhkqj6xqgCgnI9SranWHt/VwE1PfuQ7SnKgZXJHiH1JjeMqgEjxX28iHiATCs94q4BmR3PrtKKKaYmPrYcfM1rxP4fUtcZO6LY8wYEXOZDpK6shlkmjQHUmjoGGl2+c4/sDAViCL95LiHHhspRZHaopDcRPtpkIDbOh+RT6BcerkCK+95svaiEcLgGy9eqhuhXjfu+0tB9gQ1oC0uxgGZVnjmQPo4n5WzZfyvDl/JkRjhTczYiqkfK/vP3P3LVJrMQHAJjVZiT1zS9WlxVe+0VfVbcFlufcaXIOVcXcDdO/1rRk4ZKuwmBF3f7A==
INFO|2024-07-03 20:44:03.381433| SshnpdChannel |Session a7fe5f47-5d27-4903-a571-566e301cb24d connected successfully
INFO|2024-07-03 20:44:03.466513| SshnpdChannel |sshnpdAck: SshnpdAck.acknowledged
INFO|2024-07-03 20:44:03.468518| SrvImplDart |New SrvImplDart - localPort 9000
INFO|2024-07-03 20:44:03.488521| SrvImplDart |_runClientSideMulti authenticating control socket connection to rvd
INFO|2024-07-03 20:44:03.489523| SrvImplDart |_runClientSideMulti calling SocketConnector.serverToSocket
INFO|2024-07-03 20:44:03.489523| SrvImplDart |_runClientSideMulti serverToSocket is ready
INFO|2024-07-03 20:45:00.996106| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 20:45:00.996106| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 20:51:50.788827|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 20:52:00.705143|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 20:51:50.689307
SEVERE|2024-07-03 20:52:00.705143|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 20:51:50.689307
SEVERE|2024-07-03 20:52:00.705143|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 20:51:50.689307
INFO|2024-07-03 20:52:00.705143|AtLookup|Creating new connection
INFO|2024-07-03 20:52:00.898846|AtLookup|New connection created OK
INFO|2024-07-03 20:52:01.122517|AtLookup|auth success
INFO|2024-07-03 20:59:36.203280|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 20:59:46.129410|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 20:59:36.113844
SEVERE|2024-07-03 20:59:46.129410|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 20:59:36.113844
SEVERE|2024-07-03 20:59:46.129410|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 20:59:36.113844
INFO|2024-07-03 20:59:46.129410|AtLookup|Creating new connection
INFO|2024-07-03 20:59:46.695365|AtLookup|New connection created OK
INFO|2024-07-03 20:59:46.921773|AtLookup|auth success
INFO|2024-07-03 21:15:10.912386|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 21:15:20.815081|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 21:15:10.813877
SEVERE|2024-07-03 21:15:20.815081|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 21:15:10.813877
SEVERE|2024-07-03 21:15:20.815081|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 21:15:10.813877
INFO|2024-07-03 21:15:20.815081|AtLookup|Creating new connection
INFO|2024-07-03 21:15:21.014021|AtLookup|New connection created OK
INFO|2024-07-03 21:15:21.248377|AtLookup|auth success
INFO|2024-07-03 21:23:59.956861|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 21:24:09.872184|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 21:23:59.867302
SEVERE|2024-07-03 21:24:09.872184|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 21:23:59.867302
SEVERE|2024-07-03 21:24:09.872184|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 21:23:59.867302
INFO|2024-07-03 21:24:09.872184|AtLookup|Creating new connection
INFO|2024-07-03 21:24:10.505409|AtLookup|New connection created OK
INFO|2024-07-03 21:24:10.725129|AtLookup|auth success
INFO|2024-07-03 21:30:58.750143|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 21:31:08.659181|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 21:30:58.657138
SEVERE|2024-07-03 21:31:08.659181|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 21:30:58.657138
SEVERE|2024-07-03 21:31:08.659181|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 21:30:58.657138
INFO|2024-07-03 21:31:08.659181|AtLookup|Creating new connection
INFO|2024-07-03 21:31:08.850795|AtLookup|New connection created OK
INFO|2024-07-03 21:31:09.101501|AtLookup|auth success
INFO|2024-07-03 21:41:30.773689|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.224.23.101', IPv4):5702
SEVERE|2024-07-03 21:41:40.667265|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 21:41:30.660175
SEVERE|2024-07-03 21:41:40.667265|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 21:41:30.660175
SEVERE|2024-07-03 21:41:40.667265|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 21:41:30.660175
INFO|2024-07-03 21:41:40.667265|AtLookup|Creating new connection
INFO|2024-07-03 21:41:40.881133|AtLookup|New connection created OK
INFO|2024-07-03 21:41:41.145374|AtLookup|auth success
INFO|2024-07-03 21:51:50.710766|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('173.255.115.207', IPv4):5702
SEVERE|2024-07-03 21:52:00.617024|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 21:51:50.609181
SEVERE|2024-07-03 21:52:00.617024|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 21:51:50.609181
SEVERE|2024-07-03 21:52:00.617024|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 21:51:50.609181
INFO|2024-07-03 21:52:00.617024|AtLookup|Creating new connection
INFO|2024-07-03 21:52:00.829150|AtLookup|New connection created OK
INFO|2024-07-03 21:52:01.083130|AtLookup|auth success
INFO|2024-07-03 21:59:37.203527|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('173.255.115.207', IPv4):5702
SEVERE|2024-07-03 21:59:47.114570|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 21:59:37.113015
SEVERE|2024-07-03 21:59:47.114570|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 21:59:37.113015
SEVERE|2024-07-03 21:59:47.114570|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 21:59:37.113015
INFO|2024-07-03 21:59:47.114570|AtLookup|Creating new connection
INFO|2024-07-03 21:59:47.321139|AtLookup|New connection created OK
INFO|2024-07-03 21:59:47.549369|AtLookup|auth success
INFO|2024-07-03 22:18:37.528540|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('173.255.115.207', IPv4):5702
INFO|2024-07-03 22:18:37.528540|AtLookup|Creating new connection
INFO|2024-07-03 22:18:37.799767|AtLookup|New connection created OK
INFO|2024-07-03 22:18:38.050900|AtLookup|auth success
INFO|2024-07-03 22:24:00.079727|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 22:24:09.953177|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 22:23:59.942693
SEVERE|2024-07-03 22:24:09.953177|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 22:23:59.942693
SEVERE|2024-07-03 22:24:09.953177|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 22:23:59.942693
INFO|2024-07-03 22:24:09.953177|AtLookup|Creating new connection
INFO|2024-07-03 22:24:10.240999|AtLookup|New connection created OK
INFO|2024-07-03 22:24:10.563691|AtLookup|auth success
INFO|2024-07-03 22:33:47.614153|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 22:33:57.541002|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 22:33:47.525088
SEVERE|2024-07-03 22:33:57.541002|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 22:33:47.525088
SEVERE|2024-07-03 22:33:57.541002|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 22:33:47.525088
INFO|2024-07-03 22:33:57.541002|AtLookup|Creating new connection
INFO|2024-07-03 22:33:58.140780|AtLookup|New connection created OK
INFO|2024-07-03 22:33:58.379905|AtLookup|auth success
INFO|2024-07-03 22:41:30.673360|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 22:41:40.584988|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 22:41:30.576357
SEVERE|2024-07-03 22:41:40.584988|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 22:41:30.576357
SEVERE|2024-07-03 22:41:40.584988|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 22:41:30.576357
INFO|2024-07-03 22:41:40.584988|AtLookup|Creating new connection
INFO|2024-07-03 22:41:40.801538|AtLookup|New connection created OK
INFO|2024-07-03 22:41:41.050832|AtLookup|auth success
INFO|2024-07-03 22:59:37.077690|OutboundConnectionImpl|close(): calling socket.destroy() on connection to InternetAddress('35.202.117.86', IPv4):5702
SEVERE|2024-07-03 22:59:46.981419|AtLookup|Exception in sending to server, Exception: Waited for 10000 millis. No response after 2024-07-03 22:59:36.980180
SEVERE|2024-07-03 22:59:46.981419|AtLookup|Error in remote verb execution Exception: Waited for 10000 millis. No response after 2024-07-03 22:59:36.980180
SEVERE|2024-07-03 22:59:46.981419|NotificationServiceImpl (@cconstab)|Failed to fetchData caused by
Exception: Waited for 10000 millis. No response after 2024-07-03 22:59:36.980180
INFO|2024-07-03 22:59:46.981925|AtLookup|Creating new connection
INFO|2024-07-03 22:59:47.187539|AtLookup|New connection created OK
INFO|2024-07-03 22:59:47.432656|AtLookup|auth success
INFO|2024-07-03 23:09:55.798354| SrvImplDart |_runClientSideMulti Sending connect request
INFO|2024-07-03 23:09:55.798354| SrvImplDart |_runClientSideMulti authenticating new connection to rvd
INFO|2024-07-03 23:09:55.846414| SrvImplDart |_runClientSideMulti controlSocket done
INFO|2024-07-03 23:09:55.846414| SrvImplDart |_runClientSideMulti sc.done
INFO|2024-07-03 23:10:00.860033| Npt |Initializing _NptImpl
INFO|2024-07-03 23:10:00.860033| SshnpdChannel |Starting monitor for notifications with regex: "36dae143-a3fe-45ee-8361-6887b5079108.orac.sshnp@ssh_1"
INFO|2024-07-03 23:10:00.860033| SshnpdChannel |Subscribing to notifications with regex: "36dae143-a3fe-45ee-8361-6887b5079108.orac.sshnp@ssh_1"
INFO|2024-07-03 23:10:00.860033| Npt |Sending daemon feature check request
INFO|2024-07-03 23:10:00.860033| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:10:00.860033| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:10:00.861034| SrvdChannel |Subscribing to notifications with regex: "36dae143-a3fe-45ee-8361-6887b5079108.sshrvd@"
INFO|2024-07-03 23:10:00.861034| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:10:00.861034| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"36dae143-a3fe-45ee-8361-6887b5079108","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:10:00.860033"}
INFO|2024-07-03 23:10:03.801287| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:03.956871| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:03.956871| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:03.956871| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:03.956871| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:03.957872| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:03.957872| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:03.957872| SshnpdChannel |Completing the future
INFO|2024-07-03 23:10:05.962472| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:08.131297| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:10.315360| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:12.481691| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:14.639767| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:16.804514| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 23:10:18.001546| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 23:10:23.011463| Npt |Initializing _NptImpl
INFO|2024-07-03 23:10:23.011463| SshnpdChannel |Starting monitor for notifications with regex: "5699bf35-b6af-40a6-8184-96f38dbcaac2.orac.sshnp@ssh_1"
INFO|2024-07-03 23:10:23.011463| SshnpdChannel |Subscribing to notifications with regex: "5699bf35-b6af-40a6-8184-96f38dbcaac2.orac.sshnp@ssh_1"
INFO|2024-07-03 23:10:23.011463| Npt |Sending daemon feature check request
INFO|2024-07-03 23:10:23.011463| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:10:23.011463| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:10:23.011463| SrvdChannel |Subscribing to notifications with regex: "5699bf35-b6af-40a6-8184-96f38dbcaac2.sshrvd@"
INFO|2024-07-03 23:10:23.012464| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:10:23.012464| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"5699bf35-b6af-40a6-8184-96f38dbcaac2","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:10:23.011463"}
INFO|2024-07-03 23:10:23.897922| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.897922| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.897922| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.897922| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.898925| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.898925| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.898925| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:23.898925| SshnpdChannel |Completing the future
INFO|2024-07-03 23:10:25.530651| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:27.711428| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:29.877409| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:32.049813| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:34.209657| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:36.386378| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:38.562243| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 23:10:39.763193| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 23:10:44.772937| Npt |Initializing _NptImpl
INFO|2024-07-03 23:10:44.772937| SshnpdChannel |Starting monitor for notifications with regex: "79df34be-6605-422c-aa05-57b680315a4f.orac.sshnp@ssh_1"
INFO|2024-07-03 23:10:44.772937| SshnpdChannel |Subscribing to notifications with regex: "79df34be-6605-422c-aa05-57b680315a4f.orac.sshnp@ssh_1"
INFO|2024-07-03 23:10:44.772937| Npt |Sending daemon feature check request
INFO|2024-07-03 23:10:44.772937| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:10:44.772937| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:10:44.772937| SrvdChannel |Subscribing to notifications with regex: "79df34be-6605-422c-aa05-57b680315a4f.sshrvd@"
INFO|2024-07-03 23:10:44.773935| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:10:44.773935| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"79df34be-6605-422c-aa05-57b680315a4f","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:10:44.772937"}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.984993| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.985994| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:10:45.985994| SshnpdChannel |Completing the future
INFO|2024-07-03 23:10:47.583372| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:49.753523| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:51.929318| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:54.094919| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:56.266120| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:10:58.439141| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:00.609330| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 23:11:01.793748| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 23:11:06.806917| Npt |Initializing _NptImpl
INFO|2024-07-03 23:11:06.806917| SshnpdChannel |Starting monitor for notifications with regex: "ef8bcc72-b56d-4261-a61f-299072a70d1b.orac.sshnp@ssh_1"
INFO|2024-07-03 23:11:06.806917| SshnpdChannel |Subscribing to notifications with regex: "ef8bcc72-b56d-4261-a61f-299072a70d1b.orac.sshnp@ssh_1"
INFO|2024-07-03 23:11:06.806917| Npt |Sending daemon feature check request
INFO|2024-07-03 23:11:06.806917| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:11:06.806917| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:11:06.806917| SrvdChannel |Subscribing to notifications with regex: "ef8bcc72-b56d-4261-a61f-299072a70d1b.sshrvd@"
INFO|2024-07-03 23:11:06.807917| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:11:06.807917| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"ef8bcc72-b56d-4261-a61f-299072a70d1b","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:11:06.806917"}
INFO|2024-07-03 23:11:07.907577| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.907577| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.907577| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.907577| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.908578| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.908578| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.908578| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.908578| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.908578| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:07.908578| SshnpdChannel |Completing the future
INFO|2024-07-03 23:11:09.310954| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:11.475119| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:13.655144| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:15.816416| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:17.983360| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:20.151255| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:22.330202| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 23:11:23.518983| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 23:11:28.526511| Npt |Initializing _NptImpl
INFO|2024-07-03 23:11:28.526511| SshnpdChannel |Starting monitor for notifications with regex: "48d7a842-a336-4b0d-9e2a-101a48215061.orac.sshnp@ssh_1"
INFO|2024-07-03 23:11:28.526511| SshnpdChannel |Subscribing to notifications with regex: "48d7a842-a336-4b0d-9e2a-101a48215061.orac.sshnp@ssh_1"
INFO|2024-07-03 23:11:28.527049| Npt |Sending daemon feature check request
INFO|2024-07-03 23:11:28.527049| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:11:28.527049| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:11:28.527049| SrvdChannel |Subscribing to notifications with regex: "48d7a842-a336-4b0d-9e2a-101a48215061.sshrvd@"
INFO|2024-07-03 23:11:28.527049| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:11:28.527049| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"48d7a842-a336-4b0d-9e2a-101a48215061","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:11:28.526511"}
INFO|2024-07-03 23:11:29.345701| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.345701| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.345701| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:29.346705| SshnpdChannel |Completing the future
INFO|2024-07-03 23:11:30.999742| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:33.161172| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:35.332587| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:37.502107| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:39.663366| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:41.830362| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:44.009387| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 23:11:45.203390| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 23:11:50.207681| Npt |Initializing _NptImpl
INFO|2024-07-03 23:11:50.207681| SshnpdChannel |Starting monitor for notifications with regex: "e9548175-33f5-4018-84aa-5637fbc005b7.orac.sshnp@ssh_1"
INFO|2024-07-03 23:11:50.207681| SshnpdChannel |Subscribing to notifications with regex: "e9548175-33f5-4018-84aa-5637fbc005b7.orac.sshnp@ssh_1"
INFO|2024-07-03 23:11:50.207681| Npt |Sending daemon feature check request
INFO|2024-07-03 23:11:50.207681| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:11:50.207681| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:11:50.207681| SrvdChannel |Subscribing to notifications with regex: "e9548175-33f5-4018-84aa-5637fbc005b7.sshrvd@"
INFO|2024-07-03 23:11:50.208679| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:11:50.208679| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"e9548175-33f5-4018-84aa-5637fbc005b7","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:11:50.207681"}
INFO|2024-07-03 23:11:51.215716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.215716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.215716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.215716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:11:51.216716| SshnpdChannel |Completing the future
INFO|2024-07-03 23:11:52.713116| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:54.867924| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:57.046532| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:11:59.205076| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:12:01.373072| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:12:03.533834| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:12:05.716307| SrvdChannel |Still waiting for srvd response
WARNING|2024-07-03 23:12:06.903338| SrvdChannel |Timed out waiting for srvd response
INFO|2024-07-03 23:12:11.884668| SrvdChannel |Received from srvd: 85.239.53.170,46123,44007,2024-07-04T06:12:10.792736
INFO|2024-07-03 23:12:11.884668| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:46123:44007 rvdNonce: 2024-07-04T06:12:10.792736
INFO|2024-07-03 23:12:11.884668| SrvdChannel |Daemon will connect to: 85.239.53.170:44007
INFO|2024-07-03 23:12:11.913670| Npt |Initializing _NptImpl
INFO|2024-07-03 23:12:11.913670| SshnpdChannel |Starting monitor for notifications with regex: "a743761e-15fb-4b5a-8f62-6d24f29a906a.orac.sshnp@ssh_1"
INFO|2024-07-03 23:12:11.913670| SshnpdChannel |Subscribing to notifications with regex: "a743761e-15fb-4b5a-8f62-6d24f29a906a.orac.sshnp@ssh_1"
INFO|2024-07-03 23:12:11.913670| Npt |Sending daemon feature check request
INFO|2024-07-03 23:12:11.913670| SshnpdChannel |Subscribing to notifications with regex: "heartbeat.orac.sshnp"
INFO|2024-07-03 23:12:11.913670| SshnpdChannel |Sending ping to sshnpd
INFO|2024-07-03 23:12:11.913670| SrvdChannel |Subscribing to notifications with regex: "a743761e-15fb-4b5a-8f62-6d24f29a906a.sshrvd@"
INFO|2024-07-03 23:12:11.913670| SrvdChannel |Started listening for srvd response
INFO|2024-07-03 23:12:11.914669| SrvdChannel |Sending notification to srvd with key @stream:orac.request_ports.sshrvd@cconstab and value {"sessionId":"a743761e-15fb-4b5a-8f62-6d24f29a906a","atSignA":"@cconstab","atSignB":"@ssh_1","authenticateSocketA":true,"authenticateSocketB":true,"clientNonce":"2024-07-03T23:12:11.913670"}
INFO|2024-07-03 23:12:14.925174| SrvdChannel |Received from srvd: 85.239.53.170,41685,37761,2024-07-04T06:12:11.011350
INFO|2024-07-03 23:12:14.925174| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:41685:37761 rvdNonce: 2024-07-04T06:12:11.011350
INFO|2024-07-03 23:12:14.925174| SrvdChannel |Daemon will connect to: 85.239.53.170:37761
INFO|2024-07-03 23:12:16.662764| SrvdChannel |Received from srvd: 85.239.53.170,34639,39499,2024-07-04T06:12:10.847263
INFO|2024-07-03 23:12:16.662764| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:34639:39499 rvdNonce: 2024-07-04T06:12:10.847263
INFO|2024-07-03 23:12:16.662764| SrvdChannel |Daemon will connect to: 85.239.53.170:39499
INFO|2024-07-03 23:12:19.462414| SrvdChannel |Received from srvd: 85.239.53.170,33897,40463,2024-07-04T06:12:11.067460
INFO|2024-07-03 23:12:19.462414| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:33897:40463 rvdNonce: 2024-07-04T06:12:11.067460
INFO|2024-07-03 23:12:19.462414| SrvdChannel |Daemon will connect to: 85.239.53.170:40463
INFO|2024-07-03 23:12:20.977929| SrvdChannel |Received from srvd: 85.239.53.170,39803,33515,2024-07-04T06:12:10.902363
INFO|2024-07-03 23:12:20.977929| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:39803:33515 rvdNonce: 2024-07-04T06:12:10.902363
INFO|2024-07-03 23:12:20.977929| SrvdChannel |Daemon will connect to: 85.239.53.170:33515
INFO|2024-07-03 23:12:23.668531| SrvdChannel |Received from srvd: 85.239.53.170,46461,44357,2024-07-04T06:12:10.957013
INFO|2024-07-03 23:12:23.669529| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:46461:44357 rvdNonce: 2024-07-04T06:12:10.957013
INFO|2024-07-03 23:12:23.669529| SrvdChannel |Daemon will connect to: 85.239.53.170:44357
INFO|2024-07-03 23:12:27.344943| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.344943| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.344943| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.344943| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.345944| SshnpdChannel |Received ping response from @ssh_1 : @cconstab:heartbeat.orac.sshnp@ssh_1 : {"devicename":"orac","version":"5.3.0","corePackageVersion":"6.1.0","supportedFeatures":{"srAuth":true,"srE2ee":true,"acceptsPublicKeys":false,"supportsPortChoice":true,"adjustableTimeout":true},"allowedServices":["localhost:22","localhost:5201","192.168.1.90:445","192.168.1.90:139"]}
INFO|2024-07-03 23:12:27.346953| SshnpdChannel |Completing the future
INFO|2024-07-03 23:12:28.263446| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:12:30.424340| SrvdChannel |Still waiting for srvd response
INFO|2024-07-03 23:12:32.023855| SrvdChannel |Received from srvd: 85.239.53.170,44281,44841,2024-07-04T06:12:28.297290
INFO|2024-07-03 23:12:32.023855| SrvdChannel |Received from srvd: rvdHost:clientPort:daemonPort 85.239.53.170:44281:44841 rvdNonce: 2024-07-04T06:12:28.297290
INFO|2024-07-03 23:12:32.023855| SrvdChannel |Daemon will connect to: 85.239.53.170:44841
INFO|2024-07-03 23:12:32.051863| Npt |Sending session request to the device daemon
INFO|2024-07-03 23:12:34.957353| SshnpdChannel |Received a743761e-15fb-4b5a-8f62-6d24f29a906a.orac.sshnp@ssh_1 notification
SHOUT|2024-07-03 23:12:34.957353| SshnpdChannel |invalid daemon response: Failed to start up the daemon side of the srv socket tunnel : SHOUT|2024-07-03 23:12:34.599810| srv.main |Exception running srv : SocketException: Connection timed out, host: 85.239.53.170, port: 44841
INFO|2024-07-03 23:12:35.078631| SshnpdChannel |sshnpdAck: SshnpdAck.acknowledgedWithErrors
Error : Received error response from the device daemon
Stack Trace: #0 _NptImpl.run (package:noports_core/src/npt/npt.dart:270)
<asynchronous suspension>
#1 main.<anonymous closure> (file:///d:/a/noports/noports/packages/dart/sshnoports/bin/npt.dart:329)
<asynchronous suspension>
#2 main (file:///d:/a/noports/noports/packages/dart/sshnoports/bin/npt.dart:66)
<asynchronous suspension>
PS C:\Users\colin\Downloads\sshnp-windows-x64 (2)\sshnp>
Will pick this up
Fix in #1207 ... "Works on my machine" 😀, have requested verification from @cconstab
Will test for u
Describe the bug
NPT can crash hard so needs to be run as a service/loop
Steps to reproduce
Expected behavior
No crash just a restart
Screenshots
Smartphones
Were you using an atApplication when the bug was found?
No response
Additional context
No response