Very long startup time [fixed]

dl2sbadl2sba
edited June 2018 in Problems Now Fixed
Hi everybody

some days ago my KIWI arrived and basically worked well. I think on of the first things he did, was to upgrade himself to 1.114.

I have discovered now the problem, that it takes sometimes a very long time (>10min) until the KIWID is available from outside. The log shows multiple of these entries ... abt. 1 every minute?

Aug 19 05:56:56 kiwisdr kiwid: 0:00:00 ....      KiwiSDR v1.114 --------------------------------------------------------------------
Aug 19 05:56:56 kiwisdr kiwid: 0:00:00 ....      compiled: Aug 17 2017 05:38:27
Aug 19 05:56:56 kiwisdr kiwid: 0:00:00 ....      Debian 8
Aug 19 05:56:56 kiwisdr kiwid: 0:00:00 ....      background mode: delaying start 30 secs...
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/kiwi.json: 111 tokens
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/admin.json: 55 tokens
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      serial number from EEPROM: 2128
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      883 dx entries
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      listening on default port 8073/8073 for "openwebrx"
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      webserver for "openwebrx" on port [::]:8073
Aug 19 05:57:26 kiwisdr kiwid: 0:00:30 ....      ### using SPI_DEV
Aug 19 05:57:27 kiwisdr kiwid: 0:00:31 ....      FPGA version 1
Aug 19 05:57:28 kiwisdr kiwid: 0:00:32 ....      using DC_offsets: I -0.020000 Q -0.020000
Aug 19 05:57:30 kiwisdr kiwid: 0:00:34 ....      (0 users)
Aug 19 05:57:30 kiwisdr kiwid: 0:00:34 ....      UPDATE: check scheduled (startup)
Aug 19 05:57:30 kiwisdr kiwid: 0:00:34 ....      UPDATE: checking for updates
Aug 19 05:57:31 kiwisdr kiwid: 0:00:35 ....      DDNS: lat/lon = (48.927900, 10.137200)
Aug 19 05:57:31 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv4 <192.168.2.198> 0xc0a802c6 /24 0xffffff00
Aug 19 05:57:31 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv6 LINK-LOCAL <fe80::9a5d:adff:fe7f:456a%eth0> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00:
Aug 19 05:57:31 kiwisdr kiwid: 0:00:35 ....      UPDATE: version 1.114 is current
Aug 19 05:57:31 kiwisdr kiwid: 0:00:35 ....      UPDATE: daily restart..
Aug 19 05:57:43 kiwisdr kiwid: 0:00:00 ....      KiwiSDR v1.114 --------------------------------------------------------------------
Aug 19 05:57:43 kiwisdr kiwid: 0:00:00 ....      compiled: Aug 17 2017 05:38:27
Aug 19 05:57:43 kiwisdr kiwid: 0:00:00 ....      Debian 8
Aug 19 05:57:43 kiwisdr kiwid: 0:00:00 ....      background mode: delaying start 30 secs...
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/kiwi.json: 111 tokens
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/admin.json: 55 tokens
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      serial number from EEPROM: 2128
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      883 dx entries
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      listening on default port 8073/8073 for "openwebrx"
Aug 19 05:58:13 kiwisdr kiwid: 0:00:30 ....      webserver for "openwebrx" on port [::]:8073
Aug 19 05:58:14 kiwisdr kiwid: 0:00:31 ....      ### using SPI_DEV
Aug 19 05:58:15 kiwisdr kiwid: 0:00:32 ....      FPGA version 1
Aug 19 05:58:15 kiwisdr kiwid: 0:00:32 ....      using DC_offsets: I -0.020000 Q -0.020000
Aug 19 05:58:17 kiwisdr kiwid: 0:00:34 ....      (0 users)
Aug 19 05:58:17 kiwisdr kiwid: 0:00:34 ....      UPDATE: check scheduled (startup)
Aug 19 05:58:17 kiwisdr kiwid: 0:00:34 ....      UPDATE: checking for updates
Aug 19 05:58:18 kiwisdr kiwid: 0:00:35 ....      DDNS: lat/lon = (48.927900, 10.137200)
Aug 19 05:58:18 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv4 <192.168.2.198> 0xc0a802c6 /24 0xffffff00
Aug 19 05:58:18 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv6 LINK-LOCAL <fe80::9a5d:adff:fe7f:456a%eth0> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00:
Aug 19 05:58:18 kiwisdr kiwid: 0:00:35 ....      UPDATE: version 1.114 is current
Aug 19 05:58:18 kiwisdr kiwid: 0:00:35 ....      UPDATE: daily restart..
Aug 19 05:58:30 kiwisdr kiwid: 0:00:00 ....      KiwiSDR v1.114 --------------------------------------------------------------------
Aug 19 05:58:30 kiwisdr kiwid: 0:00:00 ....      compiled: Aug 17 2017 05:38:27
Aug 19 05:58:30 kiwisdr kiwid: 0:00:00 ....      Debian 8
Aug 19 05:58:30 kiwisdr kiwid: 0:00:00 ....      background mode: delaying start 30 secs...
Aug 19 05:59:00 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/kiwi.json: 111 tokens
Aug 19 05:59:00 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/admin.json: 55 tokens
Aug 19 05:59:01 kiwisdr kiwid: 0:00:30 ....      serial number from EEPROM: 2128
Aug 19 05:59:01 kiwisdr kiwid: 0:00:31 ....      reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Aug 19 05:59:01 kiwisdr kiwid: 0:00:31 ....      883 dx entries
Aug 19 05:59:01 kiwisdr kiwid: 0:00:31 ....      listening on default port 8073/8073 for "openwebrx"
Aug 19 05:59:01 kiwisdr kiwid: 0:00:31 ....      webserver for "openwebrx" on port [::]:8073
Aug 19 05:59:01 kiwisdr kiwid: 0:00:31 ....      ### using SPI_DEV
Aug 19 05:59:02 kiwisdr kiwid: 0:00:32 ....      FPGA version 1
Aug 19 05:59:02 kiwisdr kiwid: 0:00:32 ....      using DC_offsets: I -0.020000 Q -0.020000
Aug 19 05:59:05 kiwisdr kiwid: 0:00:35 ....      (0 users)
Aug 19 05:59:05 kiwisdr kiwid: 0:00:35 ....      UPDATE: check scheduled (startup)
Aug 19 05:59:05 kiwisdr kiwid: 0:00:35 ....      UPDATE: checking for updates
Aug 19 05:59:05 kiwisdr kiwid: 0:00:35 ....      DDNS: lat/lon = (48.927900, 10.137200)
Aug 19 05:59:05 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv4 <192.168.2.198> 0xc0a802c6 /24 0xffffff00
Aug 19 05:59:05 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv6 LINK-LOCAL <fe80::9a5d:adff:fe7f:456a%eth0> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00:
Aug 19 05:59:06 kiwisdr kiwid: 0:00:36 ....      UPDATE: version 1.114 is current
Aug 19 05:59:06 kiwisdr kiwid: 0:00:36 ....      UPDATE: daily restart..
Aug 19 05:59:17 kiwisdr kiwid: 0:00:00 ....      KiwiSDR v1.114 --------------------------------------------------------------------
Aug 19 05:59:17 kiwisdr kiwid: 0:00:00 ....      compiled: Aug 17 2017 05:38:27
Aug 19 05:59:17 kiwisdr kiwid: 0:00:00 ....      Debian 8
Aug 19 05:59:17 kiwisdr kiwid: 0:00:00 ....      background mode: delaying start 30 secs...
Aug 19 05:59:47 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/kiwi.json: 111 tokens
Aug 19 05:59:47 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/admin.json: 55 tokens
Aug 19 05:59:48 kiwisdr kiwid: 0:00:31 ....      serial number from EEPROM: 2128
Aug 19 05:59:48 kiwisdr kiwid: 0:00:31 ....      reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Aug 19 05:59:48 kiwisdr kiwid: 0:00:31 ....      883 dx entries
Aug 19 05:59:48 kiwisdr kiwid: 0:00:31 ....      listening on default port 8073/8073 for "openwebrx"
Aug 19 05:59:48 kiwisdr kiwid: 0:00:31 ....      webserver for "openwebrx" on port [::]:8073
Aug 19 05:59:48 kiwisdr kiwid: 0:00:31 ....      ### using SPI_DEV
Aug 19 05:59:49 kiwisdr kiwid: 0:00:32 ....      FPGA version 1
Aug 19 05:59:50 kiwisdr kiwid: 0:00:32 ....      using DC_offsets: I -0.020000 Q -0.020000
Aug 19 05:59:52 kiwisdr kiwid: 0:00:35 ....      (0 users)
Aug 19 05:59:52 kiwisdr kiwid: 0:00:35 ....      UPDATE: check scheduled (startup)
Aug 19 05:59:52 kiwisdr kiwid: 0:00:35 ....      UPDATE: checking for updates
Aug 19 05:59:52 kiwisdr kiwid: 0:00:35 ....      DDNS: lat/lon = (48.927900, 10.137200)
Aug 19 05:59:52 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv4 <192.168.2.198> 0xc0a802c6 /24 0xffffff00
Aug 19 05:59:52 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv6 LINK-LOCAL <fe80::9a5d:adff:fe7f:456a%eth0> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00:
Aug 19 05:59:53 kiwisdr kiwid: 0:00:36 ....      UPDATE: version 1.114 is current
Aug 19 05:59:53 kiwisdr kiwid: 0:00:36 ....      UPDATE: daily restart..
Aug 19 06:00:05 kiwisdr kiwid: 0:00:00 ....      KiwiSDR v1.114 --------------------------------------------------------------------
Aug 19 06:00:05 kiwisdr kiwid: 0:00:00 ....      compiled: Aug 17 2017 05:38:27
Aug 19 06:00:05 kiwisdr kiwid: 0:00:00 ....      Debian 8
Aug 19 06:00:05 kiwisdr kiwid: 0:00:00 ....      background mode: delaying start 30 secs...
Aug 19 06:00:35 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/kiwi.json: 111 tokens
Aug 19 06:00:35 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/admin.json: 55 tokens
Aug 19 06:00:35 kiwisdr kiwid: 0:00:30 ....      serial number from EEPROM: 2128
Aug 19 06:00:35 kiwisdr kiwid: 0:00:30 ....      reading configuration from file /root/kiwi.config/dx.json: 7052 tokens
Aug 19 06:00:35 kiwisdr kiwid: 0:00:30 ....      883 dx entries
Aug 19 06:00:36 kiwisdr kiwid: 0:00:30 ....      listening on default port 8073/8073 for "openwebrx"
Aug 19 06:00:36 kiwisdr kiwid: 0:00:30 ....      webserver for "openwebrx" on port [::]:8073
Aug 19 06:00:36 kiwisdr kiwid: 0:00:31 ....      ### using SPI_DEV
Aug 19 06:00:37 kiwisdr kiwid: 0:00:32 ....      FPGA version 1
Aug 19 06:00:37 kiwisdr kiwid: 0:00:32 ....      using DC_offsets: I -0.020000 Q -0.020000
Aug 19 06:00:40 kiwisdr kiwid: 0:00:34 ....      (0 users)
Aug 19 06:00:40 kiwisdr kiwid: 0:00:34 ....      UPDATE: check scheduled (startup)
Aug 19 06:00:40 kiwisdr kiwid: 0:00:35 ....      UPDATE: checking for updates
Aug 19 06:00:40 kiwisdr kiwid: 0:00:35 ....      DDNS: lat/lon = (48.927900, 10.137200)
Aug 19 06:00:40 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv4 <192.168.2.198> 0xc0a802c6 /24 0xffffff00
Aug 19 06:00:40 kiwisdr kiwid: 0:00:35 ....      DDNS: private IPv6 LINK-LOCAL <fe80::9a5d:adff:fe7f:456a%eth0> /64 ff:ff:ff:ff:ff:ff:ff:ff:00:00:00:00:00:00:00:00:
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      UPDATE: version 1.114 is current
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      SERVER-POOL: 5 ip addresses for public.kiwisdr.com
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      SERVER-POOL: #1 64.136.200.36
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      SERVER-POOL: #2 217.211.97.46
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      SERVER-POOL: #3 81.236.135.130
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      SERVER-POOL: #4 103.26.16.225
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      SERVER-POOL: #5 89.17.134.135
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      DDNS: public ip 92.75.128.147
Aug 19 06:00:41 kiwisdr kiwid: 0:00:36 ....      auto NAT is set false
Aug 19 06:00:47 kiwisdr kiwid: 0:00:42 ....      sdr.hu registration: "ERROR (your IP does not match URL, 84.63.94.13 != 92.75.128.147)"
Aug 19 06:00:50 kiwisdr kiwid: 0:00:45 ....      TIMEZONE: lat/lon from sdr.hu config: (48.600000, 9.200000)
Aug 19 06:00:50 kiwisdr kiwid: 0:00:45 ....      TIMEZONE: for (48.600000, 9.200000): utc_offset=3600/1.0 dst_offset=3600/1.0
Aug 19 06:00:50 kiwisdr kiwid: 0:00:45 ....      TIMEZONE: "Europe/Berlin", "Central European Summer Time"
Aug 19 06:02:50 kiwisdr kiwid: 0:02:45 ....      sdr.hu registration: "ERROR (your IP does not match URL, 84.63.94.13 != 92.75.128.147)"
Aug 19 06:02:57 kiwisdr kiwid: 0:02:51 .... [00] isLocal_IP TRUE IPv4/4_6 remote_ip 192.168.2.169 ip_client 192.168.2.169/0xc0a802a9 ip_server[IPv4] 192.1

until finally the SDR is up and running? When it runs, it runs reliable!
I've measured the power - peak is 960mA and the powersupply seams to be fine.

Any idea what causes this behavior?

//Dietmar

Comments

  • jksjks
    edited August 2017
    Okay, I see what's happening. You found an interesting bug. I will fix it in the next release. But for now set to "off" the "daily restart" option on the control tab of the admin page. It is off by default and few people will be using it probably.

  • dl2sbadl2sba
    Hi JKS

    thanks for this quick analysis - I've disabled it - let's see 

    //Dietmar
Sign In or Register to comment.