kx1t / docker-skysquitter

a Docker container to feed SkySquitter with Beast data
MIT License
3 stars 0 forks source link

TypeError: sigterm_handler() takes 0 positional arguments but 2 were #19

Open YipYup opened 1 year ago

YipYup commented 1 year ago

There may be more than one issue here, and if so, I'll open another issue if directed. I suspect there is a condition leading to a lack of data being sent to the beast-feeder. I think what is occurring is that the timeout of 150 seconds of no data received is being met, and the service is being shut down and restarted, but is not handling the signal it receives.

Log Excerpt:

[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder] Traceback (most recent call last):
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]   File "/usr/local/bin/beast-feeder", line 429, in <module>
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]     listen_to_receiver()
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]   File "/usr/local/bin/beast-feeder", line 190, in listen_to_receiver
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]     recv_bytes = bytearray(sock_recv.recv(RECV_BYTES_SIZE))
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder] TypeError: sigterm_handler() takes 0 positional arguments but 2 were given

Entire session log:

[Thu Mar 16 11:52:54 UTC 2023][skysquitter/clockdiff-service] Started as an s6 service; logging is verbose
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/watchdog-log-pruner] Started as an s6 service
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-watchdog] Started as an s6 service
[services.d] done.
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Started as an s6 service; logging is verbose
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] SkySquitter Container Version: 230316-0259
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Beast-Feeder Version: 221216-0038
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Starting the Data Connector with the following command line:
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] /usr/local/bin/beast-feeder readsb 30005 10.9.2.1 11092 TRUE 400
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] SKYSQUITTER BEAST-FEEDER
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] build 15.221216-0038.02
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Configuration:
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Recv host: readsb
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Recv port: 30005
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Dest host: 10.9.2.1
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Dest port: 11092
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Set Timestamp: True
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Clock diff limit: 400ms
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Init TCP connection to Receiver
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Init UDP connection to Destination
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Connect to Receiver
[Thu Mar 16 11:52:54 UTC 2023][skysquitter/ssq-feeder] Start listening...
[Thu Mar 16 11:52:57 UTC 2023][skysquitter/clockdiff-service] Clock drift for gpsdntpd
: 0 msec/0 msec
[Thu Mar 16 11:54:13 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 79 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:54:20 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 86 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:54:28 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 94 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:54:35 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 101 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:54:42 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 108 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:54:49 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 115 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:54:57 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 123 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:55:04 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 130 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:55:11 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 137 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:55:18 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 144 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Maximum Failure Time of 150 seconds exceeded.
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: Performing last-ditch effort to restart the feeder...
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder] Traceback (most recent call last):
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder]   File "/usr/local/bin/beast-feeder", line 429, in <module>
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder]     listen_to_receiver()
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder]   File "/usr/local/bin/beast-feeder", line 190, in listen_to_receiver
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder]     recv_bytes = bytearray(sock_recv.recv(RECV_BYTES_SIZE))
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder] TypeError: sigterm_handler() takes 0 positional arguments but 2 were given
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder] ---------------------------------------------------------------
[Thu Mar 16 11:55:26 UTC 2023][skysquitter/ssq-feeder] The Data Connector exited... we will restart in 10 seconds
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Started as an s6 service; logging is verbose
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] SkySquitter Container Version: 230316-0259
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Beast-Feeder Version: 221216-0038
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Starting the Data Connector with the following command line:
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] /usr/local/bin/beast-feeder readsb 30005 10.9.2.1 11092 TRUE 400
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] SKYSQUITTER BEAST-FEEDER
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] build 15.221216-0038.02
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Configuration:
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Recv host: readsb
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Recv port: 30005
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Dest host: 10.9.2.1
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Dest port: 11092
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Set Timestamp: True
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Clock diff limit: 400ms
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Init TCP connection to Receiver
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Init UDP connection to Destination
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Connect to Receiver
[Thu Mar 16 11:55:36 UTC 2023][skysquitter/ssq-feeder] Start listening...
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: Last-ditch effort failed. Shutting down the container to auto-restart...
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder] Traceback (most recent call last):
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]   File "/usr/local/bin/beast-feeder", line 429, in <module>
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]     listen_to_receiver()
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]   File "/usr/local/bin/beast-feeder", line 190, in listen_to_receiver
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder]     recv_bytes = bytearray(sock_recv.recv(RECV_BYTES_SIZE))
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder] TypeError: sigterm_handler() takes 0 positional arguments but 2 were given
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder] ---------------------------------------------------------------
Warning: `/config/wg0.conf' is world accessible
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-feeder] The Data Connector exited... we will restart in 10 seconds
[#] ip link delete dev wg0
Warning: `/config/wg0.conf' is world accessible
wg-quick: `wg0' is not a WireGuard interface
[Thu Mar 16 11:55:39 UTC 2023][skysquitter/ssq-watchdog] Brought down the container.
[cont-finish.d] executing container finish scripts...
[cont-finish.d] done.
[s6-finish] waiting for services.
[s6-finish] sending all processes the TERM signal.
[s6-finish] sending all processes the KILL signal and exiting.
[s6-init] making user provided files available at /var/run/s6/etc...exited 0.
[s6-init] ensuring user provided files have correct perms...exited 0.
[fix-attrs.d] applying ownership & permissions fixes...
[fix-attrs.d] done.
[cont-init.d] executing container initialization scripts...
[cont-init.d] 00-libsecomp2: executing... 
[cont-init.d] 00-libsecomp2: exited 0.
[cont-init.d] 10-timestamp-set-utc: executing... 
[cont-init.d] 10-timestamp-set-utc: exited 0.
[cont-init.d] 30-get-beastfeeder-dev: executing... 
[cont-init.d] 30-get-beastfeeder-dev: exited 0.
[cont-init.d] 40-save-recv_host-ip: executing... 
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/40-save-recv_host-ip] The IP address for readsb is 192.168.168.20
[cont-init.d] 40-save-recv_host-ip: exited 0.
[cont-init.d] 50-wireguard-setkeys: executing... 
[cont-init.d] 50-wireguard-setkeys: exited 0.
[cont-init.d] 60-start-wg: executing... 
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] Warning: `/config/wg0.conf' is world accessible
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] [#] ip link add wg0 type wireguard
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] [#] wg setconf wg0 /dev/fd/63
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] [#] ip -4 address add 10.9.2.xx dev wg0
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] [#] ip link set mtu 1420 up dev wg0
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] [#] ip -4 route add 10.9.2.1/32 dev wg0
[Thu Mar 16 11:55:45 UTC 2023][skysquitter/60-start-wg] WireGuard VPN brought up.
[cont-init.d] 60-start-wg: exited 0.
[cont-init.d] 70-check-beastsource: executing... 
[Thu Mar 16 11:55:46 UTC 2023][skysquitter/70-check-beastsource] readsb:30005 is now available -- your Beast Data source is ready
[cont-init.d] 70-check-beastsource: exited 0.
[cont-init.d] 71-check-destination-host: executing... 
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/71-check-destination-host] Your SkySquitter Server at 10.9.2.1 can be pinged - we are now ready to start sending it data
[cont-init.d] 71-check-destination-host: exited 0.
[cont-init.d] done.
[services.d] starting services
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/clockdiff-service] Started as an s6 service; logging is verbose
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-watchdog] Started as an s6 service
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/watchdog-log-pruner] Started as an s6 service
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Started as an s6 service; logging is verbose
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] SkySquitter Container Version: 230316-0259
[services.d] done.
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Beast-Feeder Version: 221216-0038
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Starting the Data Connector with the following command line:
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] /usr/local/bin/beast-feeder readsb 30005 10.9.2.1 11092 TRUE 400
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] SKYSQUITTER BEAST-FEEDER
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] build 15.221216-0038.02
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Configuration:
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Recv host: readsb
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Recv port: 30005
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Dest host: 10.9.2.1
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Dest port: 11092
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Set Timestamp: True
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Clock diff limit: 400ms
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Init TCP connection to Receiver
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Init UDP connection to Destination
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Connect to Receiver
[Thu Mar 16 11:55:50 UTC 2023][skysquitter/ssq-feeder] Start listening...
[Thu Mar 16 11:55:54 UTC 2023][skysquitter/clockdiff-service] Clock drift for gpsdntpd
: 0 msec/0 msec
[Thu Mar 16 11:57:09 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 79 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:57:17 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 87 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:57:24 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 94 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:57:31 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure: No data is flowing to UDP port 11092 -- Failed for 101 seconds; Maximum Failure Time is 150 seconds..
[Thu Mar 16 11:57:38 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure Resolved: Data is flowing again to UDP port 11092.
[Thu Mar 16 11:58:14 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure Resolved: Data is flowing again to UDP port 11092.
[Thu Mar 16 12:06:03 UTC 2023][skysquitter/clockdiff-service] Clock drift for gpsdntpd
: 0 msec/0 msec
[Thu Mar 16 12:10:44 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure Resolved: Data is flowing again to UDP port 11092.
[Thu Mar 16 12:11:13 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure Resolved: Data is flowing again to UDP port 11092.
[Thu Mar 16 12:16:16 UTC 2023][skysquitter/clockdiff-service] Clock drift for gpsdntpd
: 0 msec/0 msec
[Thu Mar 16 12:23:33 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure Resolved: Data is flowing again to UDP port 11092.
[Thu Mar 16 12:24:02 UTC 2023][skysquitter/ssq-watchdog] DataFlow Failure Resolved: Data is flowing again to UDP port 11092.
[Thu Mar 16 12:26:27 UTC 2023][skysquitter/clockdiff-service] Clock drift for gpsdntpd
: 0 msec/0 msec
[Thu Mar 16 12:36:40 UTC 2023][skysquitter/clockdiff-service] Clock drift for gpsdntpd
: 0 msec/0 msec
YipYup commented 10 months ago

[2023-11-04 10:22:49.499][skysquitter/ssq-feeder] TypeError: sigterm_handler() takes 0 positional arguments but 2 were given