The KiwiSDR 2 online store is open for orders! Please visit kiwisdr.nz
KiwiSDR stuck in upgrade mode [fixed, package + build system issues]
The 7 December update took an hour. This latest has been in progress for a total of 4 hours. I rebooted the Kiwi server AND I rebooted BeagleBone (not sure of the difference, but whatever...)
Installed version: v1.359, built Dec 7 2019 23:07:34
Update to version v1.370 in progress
I can't tell from the log if it's stuck or not:
Sun Jan 12 05:46:44 00:00:00.606 KiwiSDR v1.359 --------------------------------------------------------------------
Sun Jan 12 05:46:44 00:00:00.610 compiled: Dec 7 2019 23:04:34
Sun Jan 12 05:46:44 00:00:00.612 -debian 8
Sun Jan 12 05:46:44 00:00:00.612 /etc/debian_version 8.5
Sun Jan 12 05:46:44 00:00:00.612 background mode: delaying start 30 secs...
Sun Jan 12 05:47:14 00:00:30.613 TASK MAX_TASKS 137, stack memory 35072 kB, stack size 32 k u64_t
Sun Jan 12 05:47:14 00:00:30.754 reading configuration from file /root/kiwi.config/kiwi.json: 211 tokens
Sun Jan 12 05:47:14 00:00:30.758 reading configuration from file /root/kiwi.config/admin.json: 105 tokens
Sun Jan 12 05:47:15 00:00:31.144 serial number from EEPROM: 4519
Sun Jan 12 05:47:15 00:00:31.206 reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Sun Jan 12 05:47:15 00:00:31.209 883 dx entries
Sun Jan 12 05:47:15 00:00:31.224 ADC_CLOCK: 66.665900 MHz
Sun Jan 12 05:47:15 00:00:31.224 .... firmware: SDR_RX4_WF4
Sun Jan 12 05:47:15 00:00:31.226 .... firmware: rx_chans=4 wf_chans=4
Sun Jan 12 05:47:15 00:00:31.229 .... firmware: NRX bufs=4 samps=170 loop=85 rem=0
Sun Jan 12 05:47:15 00:00:31.229 .... firmware: NWF xfer=9 samps=911 rpt=50 loop=18 rem=11
Sun Jan 12 05:47:15 00:00:31.229 .... test_deadline_update=0 err=0
Sun Jan 12 05:47:15 00:00:31.230 .... listening on default port 8073/8073 for "openwebrx"
Sun Jan 12 05:47:15 00:00:31.234 .... webserver for "openwebrx" on port [::]:8073
Sun Jan 12 05:47:15 00:00:31.465 .... ### using SPI_DEV
Sun Jan 12 05:47:15 00:00:31.467 .... SPIDEV: max_speed 48000000 bpw 32
Sun Jan 12 05:47:16 00:00:32.231 .... ping..
Sun Jan 12 05:47:16 00:00:32.231 .... ping2..
Sun Jan 12 05:47:16 00:00:32.232 .... FPGA version 1
Sun Jan 12 05:47:16 00:00:32.673 .... device DNA 26268416|24242a7f
Sun Jan 12 05:47:16 00:00:32.674 .... using DC_offsets: I -0.020000 Q -0.020000
Sun Jan 12 05:47:16 00:00:32.677 .... INITIAL clk_adj=0
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #0 "colormap"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #1 "cw_decoder"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #2 "devl"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #3 "fax"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #4 "fsk"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #5 "IBP_scan"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #6 "integrate"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #7 "iq_display"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #8 "LMS_filter"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #9 "loran_c"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #10 "navtex"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #11 "sig_gen"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #12 "S_meter"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #13 "SSTV"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #14 "TDoA"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #15 "timecode"
Sun Jan 12 05:47:16 00:00:32.679 .... ext_register: #16 "wspr"
Sun Jan 12 05:47:16 00:00:32.730 .... ip_blacklist_init: "iptables -A KIWI -s 47.88.219.24/24 -j DROP"
Sun Jan 12 05:47:16 00:00:32.758 .... DDNS: private IPv4 <192.168.1.84> 0xc0a80154 /24 0xffffff00 eth0
Sun Jan 12 05:47:16 00:00:32.761 .... DDNS: private IPv6 <2600:1700:990:1e50:8a3f:4aff:fea8:c580> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00: eth0
Sun Jan 12 05:47:16 00:00:32.764 .... DDNS: private IPv6 LINK-LOCAL /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00: eth0
Sun Jan 12 05:47:17 00:00:32.996 .... test_webserver_prio=0 err=0
Sun Jan 12 05:47:17 00:00:32.996 .... GPS starting..
Sun Jan 12 05:47:17 00:00:32.997 .... GPS_INTEG_BITS 20
Sun Jan 12 05:47:17 00:00:32.997 .... DECIM 4 FFT 16384 planning..
Sun Jan 12 05:47:20 00:00:36.011 .... UPDATE: check scheduled (startup)
Sun Jan 12 05:47:20 00:00:36.014 .... UPDATE: checking for updates
Sun Jan 12 05:47:20 00:00:36.143 .... DDNS: public ip 104.8.151.106 from extreme-ip-lookup.com/json
Sun Jan 12 05:47:20 00:00:36.148 .... DDNS: lat/lon = (36.072640, -79.791980)
Sun Jan 12 05:47:21 00:00:37.042 .... UPDATE: version changed, current 1.359, new 1.370
Sun Jan 12 05:47:21 00:00:37.045 .... UPDATE: building new version..
Sun Jan 12 05:47:21 00:00:37.048 .... rx_server_user_kick rx=-1
Sun Jan 12 05:47:26 00:00:42.073 .... LRUN update_task:P3:T23((1000.000 msec) TaskSleep) (1000.000 msec) TaskSleep 5031.427
Sun Jan 12 05:47:27 00:00:43.580 .... vr=0x0 vc=0x0
Sun Jan 12 05:47:28 00:00:43.781 .... TIMEZONE: lat/lon from DDNS: (36.072640, -79.791980)
Sun Jan 12 05:47:30 00:00:45.886 .... LOOKUP: "sdr.hu" 167.99.214.222
Sun Jan 12 05:47:30 00:00:45.887 .... LOOKUP: "sdr.hu" 2a03:b0c0:2:d0::55:7001
Sun Jan 12 05:47:31 00:00:47.090 .... task sec_CK:P2:T04((50.000 msec) TaskSleep) exited by returning
Sun Jan 12 05:47:35 00:00:51.164 .... TIMEZONE: from timezonedb.com for (36.072640, -79.791980): utc_offset=-18000/-5.0 dst_offset=0/0.0
Sun Jan 12 05:47:35 00:00:51.167 .... TIMEZONE: "EST", "America\/New_York"
Sun Jan 12 05:47:35 00:00:51.169 .... task get_TZ:P2:T03((50.000 msec) TaskSleep) exited by returning
Sun Jan 12 05:47:36 00:00:52.381 .... LOOKUP: "kiwisdr.com" 50.116.2.70
Sun Jan 12 05:47:39 00:00:55.653 .... LOOKUP: "public.kiwisdr.com" 103.26.16.240
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 64.136.200.36
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 89.17.134.135
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 142.104.83.74
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 81.236.135.130
Sun Jan 12 05:47:39 00:00:55.654 .... SERVER-POOL: 5 ip addresses for public.kiwisdr.com
Sun Jan 12 05:47:39 00:00:55.657 .... SERVER-POOL: #1 103.26.16.240
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #2 64.136.200.36
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #3 89.17.134.135
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #4 142.104.83.74
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #5 81.236.135.130
Sun Jan 12 05:47:39 00:00:55.659 .... auto NAT is set false
Sun Jan 12 05:47:40 00:00:55.897 .... PROXY: sdr_hu_dom_sel=0
Sun Jan 12 05:47:40 00:00:55.897 .... task dyn_DNS:P2:T02((50.000 msec) TaskSleep) exited by returning
Sun Jan 12 05:47:40 00:00:56.043 .... task led_task:P3:T04(-) exited by returning
Sun Jan 12 05:47:45 00:01:01.100 .... [00] PWD isLocal_if_ip: flg=0x18 fam=2 socktype=1 proto=6 addrlen=16 192.168.1.64
Sun Jan 12 05:47:45 00:01:01.100 .... [00] PWD isLocal_if_ip: TRUE IPv4/4_6 remote_ip 192.168.1.64 ip_client 192.168.1.64/0xc0a80140 ip_server[IPv4] 192.168.1.84/0xc0a80154 nm /24 0xffffff00
Sun Jan 12 05:47:45 00:01:01.104 .... [00] PWD admin config pwd set FALSE, auto-login TRUE
Sun Jan 12 05:47:45 00:01:01.107 .... [00] PWD admin ALLOWED: no config pwd set, but is_local
Sun Jan 12 05:47:45 00:01:01.107 .... [00] PWD admin admin ALLOWED: from 192.168.1.64
Sun Jan 12 05:49:51 00:03:07.451 .... UPDATE: force update check by admin
Sun Jan 12 05:49:51 00:03:07.454 .... UPDATE: update task already running
Installed version: v1.359, built Dec 7 2019 23:07:34
Update to version v1.370 in progress
I can't tell from the log if it's stuck or not:
Sun Jan 12 05:46:44 00:00:00.606 KiwiSDR v1.359 --------------------------------------------------------------------
Sun Jan 12 05:46:44 00:00:00.610 compiled: Dec 7 2019 23:04:34
Sun Jan 12 05:46:44 00:00:00.612 -debian 8
Sun Jan 12 05:46:44 00:00:00.612 /etc/debian_version 8.5
Sun Jan 12 05:46:44 00:00:00.612 background mode: delaying start 30 secs...
Sun Jan 12 05:47:14 00:00:30.613 TASK MAX_TASKS 137, stack memory 35072 kB, stack size 32 k u64_t
Sun Jan 12 05:47:14 00:00:30.754 reading configuration from file /root/kiwi.config/kiwi.json: 211 tokens
Sun Jan 12 05:47:14 00:00:30.758 reading configuration from file /root/kiwi.config/admin.json: 105 tokens
Sun Jan 12 05:47:15 00:00:31.144 serial number from EEPROM: 4519
Sun Jan 12 05:47:15 00:00:31.206 reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Sun Jan 12 05:47:15 00:00:31.209 883 dx entries
Sun Jan 12 05:47:15 00:00:31.224 ADC_CLOCK: 66.665900 MHz
Sun Jan 12 05:47:15 00:00:31.224 .... firmware: SDR_RX4_WF4
Sun Jan 12 05:47:15 00:00:31.226 .... firmware: rx_chans=4 wf_chans=4
Sun Jan 12 05:47:15 00:00:31.229 .... firmware: NRX bufs=4 samps=170 loop=85 rem=0
Sun Jan 12 05:47:15 00:00:31.229 .... firmware: NWF xfer=9 samps=911 rpt=50 loop=18 rem=11
Sun Jan 12 05:47:15 00:00:31.229 .... test_deadline_update=0 err=0
Sun Jan 12 05:47:15 00:00:31.230 .... listening on default port 8073/8073 for "openwebrx"
Sun Jan 12 05:47:15 00:00:31.234 .... webserver for "openwebrx" on port [::]:8073
Sun Jan 12 05:47:15 00:00:31.465 .... ### using SPI_DEV
Sun Jan 12 05:47:15 00:00:31.467 .... SPIDEV: max_speed 48000000 bpw 32
Sun Jan 12 05:47:16 00:00:32.231 .... ping..
Sun Jan 12 05:47:16 00:00:32.231 .... ping2..
Sun Jan 12 05:47:16 00:00:32.232 .... FPGA version 1
Sun Jan 12 05:47:16 00:00:32.673 .... device DNA 26268416|24242a7f
Sun Jan 12 05:47:16 00:00:32.674 .... using DC_offsets: I -0.020000 Q -0.020000
Sun Jan 12 05:47:16 00:00:32.677 .... INITIAL clk_adj=0
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #0 "colormap"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #1 "cw_decoder"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #2 "devl"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #3 "fax"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #4 "fsk"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #5 "IBP_scan"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #6 "integrate"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #7 "iq_display"
Sun Jan 12 05:47:16 00:00:32.677 .... ext_register: #8 "LMS_filter"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #9 "loran_c"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #10 "navtex"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #11 "sig_gen"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #12 "S_meter"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #13 "SSTV"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #14 "TDoA"
Sun Jan 12 05:47:16 00:00:32.678 .... ext_register: #15 "timecode"
Sun Jan 12 05:47:16 00:00:32.679 .... ext_register: #16 "wspr"
Sun Jan 12 05:47:16 00:00:32.730 .... ip_blacklist_init: "iptables -A KIWI -s 47.88.219.24/24 -j DROP"
Sun Jan 12 05:47:16 00:00:32.758 .... DDNS: private IPv4 <192.168.1.84> 0xc0a80154 /24 0xffffff00 eth0
Sun Jan 12 05:47:16 00:00:32.761 .... DDNS: private IPv6 <2600:1700:990:1e50:8a3f:4aff:fea8:c580> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00: eth0
Sun Jan 12 05:47:16 00:00:32.764 .... DDNS: private IPv6 LINK-LOCAL /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00: eth0
Sun Jan 12 05:47:17 00:00:32.996 .... test_webserver_prio=0 err=0
Sun Jan 12 05:47:17 00:00:32.996 .... GPS starting..
Sun Jan 12 05:47:17 00:00:32.997 .... GPS_INTEG_BITS 20
Sun Jan 12 05:47:17 00:00:32.997 .... DECIM 4 FFT 16384 planning..
Sun Jan 12 05:47:20 00:00:36.011 .... UPDATE: check scheduled (startup)
Sun Jan 12 05:47:20 00:00:36.014 .... UPDATE: checking for updates
Sun Jan 12 05:47:20 00:00:36.143 .... DDNS: public ip 104.8.151.106 from extreme-ip-lookup.com/json
Sun Jan 12 05:47:20 00:00:36.148 .... DDNS: lat/lon = (36.072640, -79.791980)
Sun Jan 12 05:47:21 00:00:37.042 .... UPDATE: version changed, current 1.359, new 1.370
Sun Jan 12 05:47:21 00:00:37.045 .... UPDATE: building new version..
Sun Jan 12 05:47:21 00:00:37.048 .... rx_server_user_kick rx=-1
Sun Jan 12 05:47:26 00:00:42.073 .... LRUN update_task:P3:T23((1000.000 msec) TaskSleep) (1000.000 msec) TaskSleep 5031.427
Sun Jan 12 05:47:27 00:00:43.580 .... vr=0x0 vc=0x0
Sun Jan 12 05:47:28 00:00:43.781 .... TIMEZONE: lat/lon from DDNS: (36.072640, -79.791980)
Sun Jan 12 05:47:30 00:00:45.886 .... LOOKUP: "sdr.hu" 167.99.214.222
Sun Jan 12 05:47:30 00:00:45.887 .... LOOKUP: "sdr.hu" 2a03:b0c0:2:d0::55:7001
Sun Jan 12 05:47:31 00:00:47.090 .... task sec_CK:P2:T04((50.000 msec) TaskSleep) exited by returning
Sun Jan 12 05:47:35 00:00:51.164 .... TIMEZONE: from timezonedb.com for (36.072640, -79.791980): utc_offset=-18000/-5.0 dst_offset=0/0.0
Sun Jan 12 05:47:35 00:00:51.167 .... TIMEZONE: "EST", "America\/New_York"
Sun Jan 12 05:47:35 00:00:51.169 .... task get_TZ:P2:T03((50.000 msec) TaskSleep) exited by returning
Sun Jan 12 05:47:36 00:00:52.381 .... LOOKUP: "kiwisdr.com" 50.116.2.70
Sun Jan 12 05:47:39 00:00:55.653 .... LOOKUP: "public.kiwisdr.com" 103.26.16.240
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 64.136.200.36
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 89.17.134.135
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 142.104.83.74
Sun Jan 12 05:47:39 00:00:55.654 .... LOOKUP: "public.kiwisdr.com" 81.236.135.130
Sun Jan 12 05:47:39 00:00:55.654 .... SERVER-POOL: 5 ip addresses for public.kiwisdr.com
Sun Jan 12 05:47:39 00:00:55.657 .... SERVER-POOL: #1 103.26.16.240
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #2 64.136.200.36
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #3 89.17.134.135
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #4 142.104.83.74
Sun Jan 12 05:47:39 00:00:55.659 .... SERVER-POOL: #5 81.236.135.130
Sun Jan 12 05:47:39 00:00:55.659 .... auto NAT is set false
Sun Jan 12 05:47:40 00:00:55.897 .... PROXY: sdr_hu_dom_sel=0
Sun Jan 12 05:47:40 00:00:55.897 .... task dyn_DNS:P2:T02((50.000 msec) TaskSleep) exited by returning
Sun Jan 12 05:47:40 00:00:56.043 .... task led_task:P3:T04(-) exited by returning
Sun Jan 12 05:47:45 00:01:01.100 .... [00] PWD isLocal_if_ip: flg=0x18 fam=2 socktype=1 proto=6 addrlen=16 192.168.1.64
Sun Jan 12 05:47:45 00:01:01.100 .... [00] PWD isLocal_if_ip: TRUE IPv4/4_6 remote_ip 192.168.1.64 ip_client 192.168.1.64/0xc0a80140 ip_server[IPv4] 192.168.1.84/0xc0a80154 nm /24 0xffffff00
Sun Jan 12 05:47:45 00:01:01.104 .... [00] PWD admin config pwd set FALSE, auto-login TRUE
Sun Jan 12 05:47:45 00:01:01.107 .... [00] PWD admin ALLOWED: no config pwd set, but is_local
Sun Jan 12 05:47:45 00:01:01.107 .... [00] PWD admin admin ALLOWED: from 192.168.1.64
Sun Jan 12 05:49:51 00:03:07.451 .... UPDATE: force update check by admin
Sun Jan 12 05:49:51 00:03:07.454 .... UPDATE: update task already running
Comments
As soon as I get a reply and get it set up I will let you know.