-- Logs begin at Thu 2023-11-09 09:28:50 UTC, end at Thu 2023-11-09 09:34:41 UTC. -- Nov 09 09:33:00 volumio sudo[7098]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:00 volumio kernel: in count:402656, out count:402652 Nov 09 09:33:00 volumio kernel: reduce cnt:173, mpll1_rate:48999976, mpll2 rate:49000489 Nov 09 09:33:00 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 09 09:33:00 volumio systemd[1]: Unit volumio.service entered failed state. Nov 09 09:33:00 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:00 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:00 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 09 09:33:00 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:00 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:00 volumio systemd[1]: Stopping Volumio Backend Module... Nov 09 09:33:00 volumio kernel: in count:402650, out count:402652 Nov 09 09:33:00 volumio kernel: add cnt:196, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:33:00 volumio wireless.js[6904]: trying... Nov 09 09:33:00 volumio sudo[7125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:00 volumio sudo[7125]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:00 volumio sudo[7125]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:01 volumio kernel: in count:402655, out count:402651 Nov 09 09:33:01 volumio kernel: reduce cnt:174, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:33:01 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:01 volumio kernel: add cnt:197, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:33:01 volumio wpa_supplicant[6984]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:33:01 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:33:01 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:33:01 volumio wireless.js[6904]: trying... Nov 09 09:33:01 volumio sudo[7128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:01 volumio sudo[7128]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:01 volumio sudo[7128]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:01 volumio kernel: in count:402655, out count:402651 Nov 09 09:33:01 volumio kernel: reduce cnt:175, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:33:02 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:02 volumio kernel: add cnt:198, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:33:02 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:02 volumio kernel: reduce cnt:176, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:33:02 volumio wireless.js[6904]: trying... Nov 09 09:33:02 volumio sudo[7132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:02 volumio sudo[7132]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:02 volumio sudo[7132]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:02 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:02] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:33:02 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:33:02 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:02] [error] handle_connect error: Underlying Transport Error Nov 09 09:33:03 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:03 volumio kernel: add cnt:199, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:33:03 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:03 volumio kernel: reduce cnt:177, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:33:03 volumio wireless.js[6904]: trying... Nov 09 09:33:03 volumio sudo[7135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:03 volumio sudo[7135]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:03 volumio sudo[7135]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:03 volumio kernel: in count:402648, out count:402651 Nov 09 09:33:03 volumio kernel: add cnt:200, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:33:04 volumio kernel: in count:402653, out count:402652 Nov 09 09:33:04 volumio kernel: reduce cnt:178, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:33:04 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:04 volumio kernel: add cnt:201, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:33:04 volumio wireless.js[6904]: trying... Nov 09 09:33:04 volumio sudo[7138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:04 volumio sudo[7138]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:04 volumio sudo[7138]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:05 volumio kernel: in count:402652, out count:402652 Nov 09 09:33:05 volumio kernel: in count:805306, out count:805304 Nov 09 09:33:05 volumio kernel: reduce cnt:179, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:33:05 volumio wireless.js[6904]: trying... Nov 09 09:33:05 volumio sudo[7141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:05 volumio sudo[7141]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:05 volumio kernel: in count:402647, out count:402652 Nov 09 09:33:05 volumio kernel: add cnt:202, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:33:05 volumio sudo[7141]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:06 volumio kernel: in count:402652, out count:402652 Nov 09 09:33:06 volumio kernel: in count:805304, out count:805304 Nov 09 09:33:06 volumio wireless.js[6904]: trying... Nov 09 09:33:06 volumio sudo[7144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:06 volumio sudo[7144]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:06 volumio sudo[7144]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:07 volumio kernel: in count:1207957, out count:1207957 Nov 09 09:33:07 volumio kernel: in count:1610609, out count:1610609 Nov 09 09:33:07 volumio kernel: in count:2013261, out count:2013261 Nov 09 09:33:07 volumio wireless.js[6904]: trying... Nov 09 09:33:08 volumio sudo[7150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:08 volumio sudo[7150]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:08 volumio sudo[7150]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:08 volumio kernel: in count:2415914, out count:2415914 Nov 09 09:33:08 volumio kernel: in count:2818566, out count:2818566 Nov 09 09:33:09 volumio wireless.js[6904]: trying... Nov 09 09:33:09 volumio sudo[7153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:09 volumio sudo[7153]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:09 volumio sudo[7153]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:09 volumio kernel: in count:3221219, out count:3221219 Nov 09 09:33:09 volumio kernel: in count:3623871, out count:3623871 Nov 09 09:33:09 volumio kernel: in count:4026523, out count:4026523 Nov 09 09:33:10 volumio wireless.js[6904]: trying... Nov 09 09:33:10 volumio sudo[7156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:10 volumio sudo[7156]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:10 volumio sudo[7156]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:10 volumio kernel: in count:4429176, out count:4429176 Nov 09 09:33:10 volumio kernel: in count:4831828, out count:4831828 Nov 09 09:33:11 volumio wireless.js[6904]: trying... Nov 09 09:33:11 volumio sudo[7159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:11 volumio sudo[7159]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:11 volumio sudo[7159]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:11 volumio kernel: in count:5234481, out count:5234480 Nov 09 09:33:11 volumio kernel: reduce cnt:180, mpll1_rate:48999976, mpll2 rate:48999976 Nov 09 09:33:11 volumio kernel: in count:402646, out count:402651 Nov 09 09:33:11 volumio kernel: add cnt:203, mpll1_rate:48999976 mpll2 rate:48999317 Nov 09 09:33:11 volumio wpa_supplicant[6984]: wlan0: Authentication with 30:c5:0f:a3:58:f4 timed out. Nov 09 09:33:11 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-DISCONNECTED bssid=30:c5:0f:a3:58:f4 reason=3 locally_generated=1 Nov 09 09:33:11 volumio kernel: CFG80211-ERROR) wl_cfg80211_check_in4way : wl_cfg80211_check_in4way: return -EBUSY cnt 1 Nov 09 09:33:11 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:12 volumio wireless.js[6904]: trying... Nov 09 09:33:12 volumio sudo[7162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:12 volumio sudo[7162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:12 volumio sudo[7162]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:12 volumio kernel: in count:805304, out count:805304 Nov 09 09:33:12 volumio kernel: in count:1207956, out count:1207956 Nov 09 09:33:12 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:12] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:33:12 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:33:12 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:12] [error] handle_connect error: Underlying Transport Error Nov 09 09:33:13 volumio wireless.js[6904]: trying... Nov 09 09:33:13 volumio kernel: in count:1610609, out count:1610608 Nov 09 09:33:13 volumio kernel: reduce cnt:181, mpll1_rate:48999976, mpll2 rate:48999903 Nov 09 09:33:13 volumio sudo[7165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:13 volumio sudo[7165]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:13 volumio sudo[7165]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:13 volumio kernel: in count:402645, out count:402651 Nov 09 09:33:13 volumio kernel: add cnt:204, mpll1_rate:48999976 mpll2 rate:48999244 Nov 09 09:33:13 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:13 volumio kernel: add cnt:205, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:33:14 volumio wireless.js[6904]: trying... Nov 09 09:33:14 volumio sudo[7168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:14 volumio sudo[7168]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:14 volumio sudo[7168]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:14 volumio kernel: in count:402655, out count:402652 Nov 09 09:33:14 volumio kernel: reduce cnt:182, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:33:14 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:14 volumio kernel: add cnt:206, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:33:15 volumio kernel: in count:402655, out count:402652 Nov 09 09:33:15 volumio kernel: reduce cnt:183, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:33:15 volumio wireless.js[6904]: trying... Nov 09 09:33:15 volumio sudo[7171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:15 volumio sudo[7171]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:15 volumio sudo[7171]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:15 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:15 volumio kernel: add cnt:207, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:33:16 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:16 volumio kernel: reduce cnt:184, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:33:16 volumio wireless.js[6904]: trying... Nov 09 09:33:16 volumio sudo[7174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:16 volumio sudo[7174]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:16 volumio sudo[7174]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:16 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:16 volumio kernel: add cnt:208, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:33:16 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:16 volumio kernel: reduce cnt:185, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:33:17 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=30:c5:0f:a3:58:f4 status_code=1 Nov 09 09:33:17 volumio kernel: connect failed event=0 e->status 1 e->reason 0 Nov 09 09:33:17 volumio kernel: CFG80211-ERROR) wl_bss_connect_done : Report connect result - connection failed Nov 09 09:33:17 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:17 volumio kernel: add cnt:209, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:33:17 volumio wireless.js[6904]: Overtime, starting plan B Nov 09 09:33:17 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:17 volumio kernel: reduce cnt:186, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:33:18 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:18 volumio kernel: add cnt:210, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:33:18 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:18 volumio kernel: reduce cnt:187, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:33:18 volumio kernel: in count:402647, out count:402651 Nov 09 09:33:18 volumio kernel: add cnt:211, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:33:19 volumio kernel: in count:402652, out count:402651 Nov 09 09:33:19 volumio kernel: reduce cnt:188, mpll1_rate:48999976, mpll2 rate:48999976 Nov 09 09:33:19 volumio kernel: in count:402646, out count:402652 Nov 09 09:33:19 volumio kernel: add cnt:212, mpll1_rate:48999976 mpll2 rate:48999317 Nov 09 09:33:20 volumio kernel: in count:402651, out count:402652 Nov 09 09:33:20 volumio kernel: add cnt:213, mpll1_rate:48999976 mpll2 rate:48999903 Nov 09 09:33:20 volumio kernel: in count:402656, out count:402652 Nov 09 09:33:20 volumio kernel: reduce cnt:189, mpll1_rate:48999976, mpll2 rate:49000489 Nov 09 09:33:20 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:21 volumio kernel: in count:805302, out count:805304 Nov 09 09:33:21 volumio kernel: add cnt:214, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:33:21 volumio kernel: in count:402655, out count:402651 Nov 09 09:33:21 volumio kernel: reduce cnt:190, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:33:22 volumio nmbd[4326]: [2023/11/09 09:33:22.009823, 0] ../source3/libsmb/nmblib.c:873(send_udp) Nov 09 09:33:22 volumio nmbd[4326]: Packet send failed to 192.168.10.255(138) ERRNO=Network is unreachable Nov 09 09:33:22 volumio nmbd[4326]: [2023/11/09 09:33:22.020203, 0] ../source3/nmbd/nmbd.c:362(reload_interfaces) Nov 09 09:33:22 volumio nmbd[4326]: reload_interfaces: No subnets to listen to. Waiting.. Nov 09 09:33:22 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:22 volumio kernel: in count:805302, out count:805304 Nov 09 09:33:22 volumio kernel: add cnt:215, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:33:22 volumio kernel: in count:402655, out count:402652 Nov 09 09:33:22 volumio kernel: reduce cnt:191, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:33:22 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:22] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:33:22 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:33:22 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:22] [error] handle_connect error: Underlying Transport Error Nov 09 09:33:23 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:23 volumio kernel: add cnt:216, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:33:23 volumio wpa_supplicant[6984]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:33:23 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:33:23 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:33:23 volumio kernel: in count:402654, out count:402652 Nov 09 09:33:23 volumio kernel: reduce cnt:192, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:33:24 volumio kernel: in count:402649, out count:402651 Nov 09 09:33:24 volumio kernel: add cnt:217, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:33:24 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:24 volumio kernel: reduce cnt:193, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:33:24 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:24 volumio kernel: add cnt:218, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:33:25 volumio kernel: in count:402652, out count:402651 Nov 09 09:33:25 volumio kernel: reduce cnt:194, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:33:25 volumio kernel: in count:402648, out count:402651 Nov 09 09:33:25 volumio kernel: add cnt:219, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:33:26 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:26 volumio kernel: reduce cnt:195, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:33:26 volumio kernel: in count:402647, out count:402651 Nov 09 09:33:26 volumio kernel: add cnt:220, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:33:26 volumio dhcpcd[6958]: timed out Nov 09 09:33:26 volumio dhcpcd[6958]: allowing 8 seconds for IPv4LL timeout Nov 09 09:33:26 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:27 volumio kernel: in count:805304, out count:805304 Nov 09 09:33:27 volumio kernel: in count:1207956, out count:1207956 Nov 09 09:33:28 volumio kernel: in count:1610609, out count:1610609 Nov 09 09:33:28 volumio kernel: in count:2013261, out count:2013261 Nov 09 09:33:28 volumio kernel: in count:2415913, out count:2415913 Nov 09 09:33:29 volumio kernel: in count:2818566, out count:2818566 Nov 09 09:33:29 volumio kernel: in count:3221218, out count:3221218 Nov 09 09:33:30 volumio kernel: in count:3623871, out count:3623871 Nov 09 09:33:30 volumio kernel: in count:4026523, out count:4026523 Nov 09 09:33:30 volumio kernel: in count:4429175, out count:4429175 Nov 09 09:33:31 volumio kernel: in count:4831828, out count:4831828 Nov 09 09:33:31 volumio kernel: in count:5234480, out count:5234480 Nov 09 09:33:32 volumio kernel: in count:5637133, out count:5637133 Nov 09 09:33:32 volumio kernel: in count:6039785, out count:6039785 Nov 09 09:33:32 volumio kernel: in count:6442437, out count:6442437 Nov 09 09:33:32 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:32] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:33:32 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:33:32 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:32] [error] handle_connect error: Underlying Transport Error Nov 09 09:33:33 volumio kernel: in count:6845090, out count:6845090 Nov 09 09:33:33 volumio wpa_supplicant[6984]: wlan0: Authentication with 30:c5:0f:a3:58:f4 timed out. Nov 09 09:33:33 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-DISCONNECTED bssid=30:c5:0f:a3:58:f4 reason=3 locally_generated=1 Nov 09 09:33:33 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Base_Linux" auth_failures=1 duration=10 reason=CONN_FAILED Nov 09 09:33:33 volumio kernel: CFG80211-ERROR) wl_cfg80211_check_in4way : wl_cfg80211_check_in4way: return -EBUSY cnt 1 Nov 09 09:33:33 volumio kernel: in count:7247742, out count:7247742 Nov 09 09:33:34 volumio kernel: in count:7650395, out count:7650394 Nov 09 09:33:34 volumio kernel: reduce cnt:196, mpll1_rate:48999976, mpll2 rate:48999976 Nov 09 09:33:34 volumio kernel: in count:402646, out count:402652 Nov 09 09:33:34 volumio kernel: add cnt:221, mpll1_rate:48999976 mpll2 rate:48999317 Nov 09 09:33:34 volumio dhcpcd[6958]: timed out Nov 09 09:33:34 volumio dhcpcd[6958]: forked to background, child pid 7179 Nov 09 09:33:34 volumio sudo[6944]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:34 volumio systemd[1]: Started Wireless Services. Nov 09 09:33:34 volumio systemd[1]: Starting Volumio Backend Module... Nov 09 09:33:34 volumio systemd[1]: Started Volumio Backend Module. Nov 09 09:33:34 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:35 volumio kernel: in count:805304, out count:805303 Nov 09 09:33:35 volumio kernel: reduce cnt:197, mpll1_rate:48999976, mpll2 rate:48999903 Nov 09 09:33:35 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:35 volumio volumio[7181]: info: ----- Volumio2 ---- Nov 09 09:33:35 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:35 volumio volumio[7181]: info: ----- System startup ---- Nov 09 09:33:35 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:35 volumio volumio[7181]: info: MYVOLUMIO Environment detected Nov 09 09:33:35 volumio kernel: in count:402645, out count:402651 Nov 09 09:33:35 volumio kernel: add cnt:222, mpll1_rate:48999976 mpll2 rate:48999244 Nov 09 09:33:35 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=30:c5:0f:a3:58:f4 status_code=1 Nov 09 09:33:35 volumio kernel: connect failed event=0 e->status 4 e->reason 1 Nov 09 09:33:35 volumio kernel: CFG80211-ERROR) wl_bss_connect_done : Report connect result - connection failed Nov 09 09:33:36 volumio volumio[7181]: info: Plugin folders cleanup Nov 09 09:33:36 volumio volumio[7181]: info: Scanning into folder /volumio/app/plugins/ Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category audio_interface Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category miscellanea Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category music_service Nov 09 09:33:36 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:36 volumio kernel: add cnt:223, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category plugins.json Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category system_controller Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category user_interface Nov 09 09:33:36 volumio volumio[7181]: info: Scanning into folder /data/plugins/ Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category accessory Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category audio_interface Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category music_service Nov 09 09:33:36 volumio volumio[7181]: info: Scanning category system_controller Nov 09 09:33:36 volumio volumio[7181]: info: Plugin folders cleanup completed Nov 09 09:33:36 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:36 volumio volumio[7181]: info: ----- Core plugins startup ---- Nov 09 09:33:36 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugins from folder /volumio/app/plugins/ Nov 09 09:33:36 volumio volumio[7181]: info: Adding plugin upnp to MyMusic Plugins Nov 09 09:33:36 volumio volumio[7181]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 09 09:33:36 volumio volumio[7181]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugins from folder /data/plugins/ Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "system"... Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "appearance"... Nov 09 09:33:36 volumio kernel: in count:402655, out count:402652 Nov 09 09:33:36 volumio kernel: reduce cnt:198, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "network"... Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "services"... Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "alsa_controller"... Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "wizard"... Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "volumio_command_line_client"... Nov 09 09:33:36 volumio volumio[7181]: info: Plugin upnp is not enabled Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "my_music"... Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "mpd"... Nov 09 09:33:36 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:36 volumio volumio[7181]: info: Plugin upnp_browser is not enabled Nov 09 09:33:36 volumio volumio[7181]: info: Loading plugin "networkfs"... Nov 09 09:33:37 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "alarm-clock"... Nov 09 09:33:37 volumio sudo[7204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro lena.baselinux.net:/DATA/mmedia/music /mnt/NAS/lena_music Nov 09 09:33:37 volumio sudo[7204]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:37 volumio sudo[7204]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:37 volumio volumio[7181]: info: Plugin airplay_emulation is not enabled Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "last_100"... Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "webradio"... Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "i2s_dacs"... Nov 09 09:33:37 volumio volumio[7181]: info: I2S DAC not set, start Auto-detection Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "volumiodiscovery"... Nov 09 09:33:37 volumio volumio[7181]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:37 volumio node[7181]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:37 volumio node[7181]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:37 volumio node[7181]: *** WARNING *** For more information see Nov 09 09:33:37 volumio node[7181]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:37 volumio node[7181]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:37 volumio node[7181]: *** WARNING *** For more information see Nov 09 09:33:37 volumio volumio[7181]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:37 volumio volumio[7181]: *** WARNING *** For more information see Nov 09 09:33:37 volumio volumio[7181]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:37 volumio volumio[7181]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:37 volumio volumio[7181]: *** WARNING *** For more information see Nov 09 09:33:37 volumio volumio[7181]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 09 09:33:37 volumio volumio[7181]: Discovery: StartAdv! undefined Nov 09 09:33:37 volumio volumio[7181]: Discovery: Started advertising... Volumio - undefined Nov 09 09:33:37 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:33:37 volumio volumio[7181]: info: Plugin volsimpleequal is not enabled Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "bandcamp"... Nov 09 09:33:37 volumio kernel: in count:805302, out count:805304 Nov 09 09:33:37 volumio kernel: add cnt:224, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:33:37 volumio volumio[7181]: info: Loading plugin "youtube2"... Nov 09 09:33:37 volumio kernel: in count:402655, out count:402652 Nov 09 09:33:37 volumio kernel: reduce cnt:199, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:33:38 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:38 volumio kernel: add cnt:225, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:33:38 volumio kernel: in count:402653, out count:402652 Nov 09 09:33:38 volumio kernel: reduce cnt:200, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:33:38 volumio kernel: in count:402649, out count:402651 Nov 09 09:33:38 volumio kernel: add cnt:226, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:33:39 volumio kernel: in count:402653, out count:402652 Nov 09 09:33:39 volumio kernel: reduce cnt:201, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "outputs"... Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "albumart"... Nov 09 09:33:39 volumio volumio[7181]: info: Plugin example_plugin is not enabled Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "inputs"... Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "updater_comm"... Nov 09 09:33:39 volumio volumio[7181]: info: Plugin mpdemulation is not enabled Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "rest_api"... Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "websocket"... Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "ir_controller"... Nov 09 09:33:39 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:39 volumio kernel: add cnt:227, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:33:39 volumio volumio[7181]: Forking 5 albumart workers Nov 09 09:33:39 volumio volumio[7181]: info: Applying required configuration parameters for plugin ir_controller Nov 09 09:33:39 volumio volumio[7181]: info: Loading plugin "radio_paradise"... Nov 09 09:33:40 volumio volumio[7181]: info: Applying required configuration parameters for plugin radio_paradise Nov 09 09:33:40 volumio volumio[7181]: info: [1699522420054] [RadioParadise] API delay: 5 Nov 09 09:33:40 volumio volumio[7181]: info: Loading plugin "backup_restore"... Nov 09 09:33:40 volumio kernel: in count:402652, out count:402652 Nov 09 09:33:40 volumio volumio[7181]: info: Loading plugin "wifireconnect"... Nov 09 09:33:40 volumio volumio[7181]: Starting albumart workers Nov 09 09:33:40 volumio volumio[7181]: Starting albumart workers Nov 09 09:33:40 volumio volumio[7181]: info: ___________ START PLUGINS ___________ Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:40 volumio volumio[7181]: info: [1699522420398] CoreMusicLibrary::Adding element Last_100 Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:40 volumio volumio[7181]: info: [1699522420406] CoreMusicLibrary::Adding element Webradio Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:40 volumio volumio[7181]: info: [1699522420421] CoreMusicLibrary::Adding element Bandcamp Discover Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:40 volumio volumio[7181]: info: [youtube2-auth] Starting auth flow... Nov 09 09:33:40 volumio volumio[7181]: Starting albumart workers Nov 09 09:33:40 volumio volumio[7181]: Starting albumart workers Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:40 volumio volumio[7181]: info: [1699522420477] CoreMusicLibrary::Adding element YouTube2 Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:40 volumio sudo[7250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/lirc/hardware.conf Nov 09 09:33:40 volumio sudo[7250]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:40 volumio kernel: in count:805306, out count:805304 Nov 09 09:33:40 volumio kernel: reduce cnt:202, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:33:40 volumio sudo[7250]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:40 volumio volumio[7181]: info: [1699522420573] CoreMusicLibrary::Adding element Radio Paradise Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:40 volumio sudo[7261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/lirc/hardware.conf /etc/lirc/lircd.conf /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 09 09:33:40 volumio sudo[7261]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:40 volumio sudo[7261]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:40 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:40 volumio volumio[7181]: info: ----- MyVolumio plugins startup ---- Nov 09 09:33:40 volumio volumio[7181]: info: ------------------------------------------- Nov 09 09:33:40 volumio volumio[7181]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 09 09:33:40 volumio volumio[7181]: info: Loading i18n strings for locale es Nov 09 09:33:40 volumio sudo[7264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start wifireconnect.timer Nov 09 09:33:40 volumio sudo[7264]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:40 volumio volumio[7181]: Updating browse sources language Nov 09 09:33:40 volumio volumio[7181]: Cannot find translation for sourceBandcamp Discover Nov 09 09:33:40 volumio volumio[7181]: Cannot find translation for sourceYouTube2 Nov 09 09:33:40 volumio volumio[7181]: Cannot find translation for sourceRadio Paradise Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:40 volumio volumio[7181]: Starting albumart workers Nov 09 09:33:40 volumio systemd[1]: Started Runs wifireconnect every 1min. Nov 09 09:33:40 volumio sudo[7264]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::initPlayerControls Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: info: BOOT COMPLETED Nov 09 09:33:40 volumio volumio[7181]: [Metrics] CommandRouter: 5s 71.97ms Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:40 volumio volumio[7181]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Nov 09 09:33:40 volumio volumio[7181]: aplay: main:722: audio open error: No such file or directory Nov 09 09:33:40 volumio volumio[7181]: Cannot play startup sound Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:33:40 volumio volumio[7181]: Express server listening on port 3000 Nov 09 09:33:40 volumio volumio[7181]: [Metrics] WebUI: 5s 314.17ms Nov 09 09:33:40 volumio volumio[7181]: Volumio Calling Home Nov 09 09:33:40 volumio volumio[7181]: info: CoreStateMachine::resetVolumioState Nov 09 09:33:40 volumio volumio[7181]: info: CoreStateMachine::getcurrentVolume Nov 09 09:33:40 volumio volumio[7181]: info: CoreCommandRouter::volumioRetrievevolume Nov 09 09:33:40 volumio volumio[7181]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 09 09:33:40 volumio volumio[7181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:33:40 volumio volumio[7181]: ReferenceError: deferred is not defined Nov 09 09:33:40 volumio volumio[7181]: at ClientRequest.req.on (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Nov 09 09:33:40 volumio volumio[7181]: at emitOne (events.js:116:13) Nov 09 09:33:40 volumio volumio[7181]: at ClientRequest.emit (events.js:211:7) Nov 09 09:33:40 volumio volumio[7181]: at TLSSocket.socketErrorListener (_http_client.js:387:9) Nov 09 09:33:40 volumio volumio[7181]: at emitOne (events.js:116:13) Nov 09 09:33:40 volumio volumio[7181]: at TLSSocket.emit (events.js:211:7) Nov 09 09:33:40 volumio volumio[7181]: at emitErrorNT (internal/streams/destroy.js:64:8) Nov 09 09:33:40 volumio volumio[7181]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Nov 09 09:33:40 volumio volumio[7181]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 09 09:33:40 volumio volumio[7181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:33:40 volumio kernel: in count:402648, out count:402651 Nov 09 09:33:40 volumio kernel: add cnt:228, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:33:41 volumio kernel: in count:402652, out count:402651 Nov 09 09:33:41 volumio kernel: reduce cnt:203, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:33:41 volumio sudo[7298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-09 09:32 Nov 09 09:33:41 volumio sudo[7298]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:41 volumio sudo[7298]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:41 volumio kernel: in count:402647, out count:402651 Nov 09 09:33:41 volumio kernel: add cnt:229, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:33:41 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 09 09:33:41 volumio systemd[1]: Unit volumio.service entered failed state. Nov 09 09:33:41 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:41 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:42 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 09 09:33:42 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:42 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:42 volumio systemd[1]: Stopping Volumio Backend Module... Nov 09 09:33:42 volumio systemd[1]: Starting Volumio Backend Module... Nov 09 09:33:42 volumio systemd[1]: Started Volumio Backend Module. Nov 09 09:33:42 volumio kernel: in count:402651, out count:402652 Nov 09 09:33:42 volumio kernel: add cnt:230, mpll1_rate:48999976 mpll2 rate:48999976 Nov 09 09:33:42 volumio kernel: in count:402656, out count:402651 Nov 09 09:33:42 volumio kernel: reduce cnt:204, mpll1_rate:48999976, mpll2 rate:49000562 Nov 09 09:33:42 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:42] [connect] Successful connection Nov 09 09:33:42 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:43 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:43 volumio volumio[7324]: info: ----- Volumio2 ---- Nov 09 09:33:43 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:43 volumio volumio[7324]: info: ----- System startup ---- Nov 09 09:33:43 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:43 volumio volumio[7324]: info: MYVOLUMIO Environment detected Nov 09 09:33:43 volumio kernel: in count:805304, out count:805303 Nov 09 09:33:43 volumio kernel: reduce cnt:205, mpll1_rate:48999976, mpll2 rate:48999903 Nov 09 09:33:43 volumio volumio[7324]: info: Plugin folders cleanup Nov 09 09:33:43 volumio volumio[7324]: info: Scanning into folder /volumio/app/plugins/ Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category audio_interface Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category miscellanea Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category music_service Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category plugins.json Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category system_controller Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category user_interface Nov 09 09:33:43 volumio volumio[7324]: info: Scanning into folder /data/plugins/ Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category accessory Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category audio_interface Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category music_service Nov 09 09:33:43 volumio volumio[7324]: info: Scanning category system_controller Nov 09 09:33:43 volumio volumio[7324]: info: Plugin folders cleanup completed Nov 09 09:33:43 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:43 volumio volumio[7324]: info: ----- Core plugins startup ---- Nov 09 09:33:43 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:43 volumio volumio[7324]: info: Loading plugins from folder /volumio/app/plugins/ Nov 09 09:33:43 volumio volumio[7324]: info: Adding plugin upnp to MyMusic Plugins Nov 09 09:33:43 volumio volumio[7324]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 09 09:33:43 volumio volumio[7324]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 09 09:33:43 volumio volumio[7324]: info: Loading plugins from folder /data/plugins/ Nov 09 09:33:43 volumio volumio[7324]: info: Loading plugin "system"... Nov 09 09:33:43 volumio volumio[7324]: info: Loading plugin "appearance"... Nov 09 09:33:43 volumio kernel: in count:402645, out count:402652 Nov 09 09:33:43 volumio kernel: add cnt:231, mpll1_rate:48999976 mpll2 rate:48999244 Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "network"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "services"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "alsa_controller"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "wizard"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "volumio_command_line_client"... Nov 09 09:33:44 volumio volumio[7324]: info: Plugin upnp is not enabled Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "my_music"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "mpd"... Nov 09 09:33:44 volumio kernel: in count:402650, out count:402652 Nov 09 09:33:44 volumio kernel: add cnt:232, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:33:44 volumio volumio[7324]: info: Plugin upnp_browser is not enabled Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "networkfs"... Nov 09 09:33:44 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "alarm-clock"... Nov 09 09:33:44 volumio sudo[7348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro lena.baselinux.net:/DATA/mmedia/music /mnt/NAS/lena_music Nov 09 09:33:44 volumio sudo[7348]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:44 volumio kernel: in count:402655, out count:402651 Nov 09 09:33:44 volumio kernel: reduce cnt:206, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:33:44 volumio sudo[7348]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:44 volumio volumio[7324]: info: Plugin airplay_emulation is not enabled Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "last_100"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "webradio"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "i2s_dacs"... Nov 09 09:33:44 volumio volumio[7324]: info: I2S DAC not set, start Auto-detection Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "volumiodiscovery"... Nov 09 09:33:44 volumio volumio[7324]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:44 volumio node[7324]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:44 volumio node[7324]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:44 volumio node[7324]: *** WARNING *** For more information see Nov 09 09:33:44 volumio node[7324]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:44 volumio node[7324]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:44 volumio node[7324]: *** WARNING *** For more information see Nov 09 09:33:44 volumio volumio[7324]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:44 volumio volumio[7324]: *** WARNING *** For more information see Nov 09 09:33:44 volumio volumio[7324]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:44 volumio volumio[7324]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:44 volumio volumio[7324]: *** WARNING *** For more information see Nov 09 09:33:44 volumio volumio[7324]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 09 09:33:44 volumio volumio[7324]: Discovery: StartAdv! undefined Nov 09 09:33:44 volumio volumio[7324]: Discovery: Started advertising... Volumio - undefined Nov 09 09:33:44 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:33:44 volumio volumio[7324]: info: Plugin volsimpleequal is not enabled Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "bandcamp"... Nov 09 09:33:44 volumio volumio[7324]: info: Loading plugin "youtube2"... Nov 09 09:33:44 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:44 volumio kernel: add cnt:233, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:33:45 volumio kernel: in count:402655, out count:402651 Nov 09 09:33:45 volumio kernel: reduce cnt:207, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:33:45 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:45 volumio kernel: add cnt:234, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:33:46 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:46 volumio kernel: reduce cnt:208, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:33:46 volumio kernel: in count:402649, out count:402651 Nov 09 09:33:46 volumio kernel: add cnt:235, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:33:46 volumio volumio[7324]: info: Loading plugin "outputs"... Nov 09 09:33:46 volumio volumio[7324]: info: Loading plugin "albumart"... Nov 09 09:33:47 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:47 volumio kernel: reduce cnt:209, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:33:47 volumio volumio[7324]: info: Plugin example_plugin is not enabled Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "inputs"... Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "updater_comm"... Nov 09 09:33:47 volumio volumio[7324]: info: Plugin mpdemulation is not enabled Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "rest_api"... Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "websocket"... Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "ir_controller"... Nov 09 09:33:47 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Base_Linux" Nov 09 09:33:47 volumio wpa_supplicant[6984]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:33:47 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:33:47 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:33:47 volumio volumio[7324]: Forking 5 albumart workers Nov 09 09:33:47 volumio volumio[7324]: info: Applying required configuration parameters for plugin ir_controller Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "radio_paradise"... Nov 09 09:33:47 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:47 volumio kernel: add cnt:236, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:33:47 volumio volumio[7324]: info: Applying required configuration parameters for plugin radio_paradise Nov 09 09:33:47 volumio volumio[7324]: info: [1699522427493] [RadioParadise] API delay: 5 Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "backup_restore"... Nov 09 09:33:47 volumio volumio[7324]: info: Loading plugin "wifireconnect"... Nov 09 09:33:47 volumio volumio[7324]: Starting albumart workers Nov 09 09:33:47 volumio volumio[7324]: Starting albumart workers Nov 09 09:33:47 volumio volumio[7324]: info: ___________ START PLUGINS ___________ Nov 09 09:33:47 volumio kernel: in count:402652, out count:402652 Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:47 volumio volumio[7324]: info: [1699522427820] CoreMusicLibrary::Adding element Last_100 Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:47 volumio volumio[7324]: info: [1699522427828] CoreMusicLibrary::Adding element Webradio Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:47 volumio volumio[7324]: info: [1699522427844] CoreMusicLibrary::Adding element Bandcamp Discover Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:47 volumio volumio[7324]: info: [youtube2-auth] Starting auth flow... Nov 09 09:33:47 volumio volumio[7324]: Starting albumart workers Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:47 volumio volumio[7324]: info: [1699522427903] CoreMusicLibrary::Adding element YouTube2 Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:47 volumio volumio[7324]: Starting albumart workers Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:47 volumio volumio[7324]: info: [1699522427944] CoreMusicLibrary::Adding element Radio Paradise Nov 09 09:33:47 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:47 volumio sudo[7397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/lirc/hardware.conf /etc/lirc/lircd.conf /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 09 09:33:47 volumio sudo[7397]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:47 volumio sudo[7397]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:47 volumio sudo[7396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/lirc/hardware.conf Nov 09 09:33:47 volumio sudo[7396]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:47 volumio sudo[7396]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:47 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:48 volumio volumio[7324]: info: ----- MyVolumio plugins startup ---- Nov 09 09:33:48 volumio volumio[7324]: info: ------------------------------------------- Nov 09 09:33:48 volumio volumio[7324]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 09 09:33:48 volumio sudo[7409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start wifireconnect.timer Nov 09 09:33:48 volumio sudo[7409]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:48 volumio systemd[1]: Started Runs wifireconnect every 1min. Nov 09 09:33:48 volumio sudo[7409]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:48 volumio volumio[7324]: info: Loading i18n strings for locale es Nov 09 09:33:48 volumio volumio[7324]: Updating browse sources language Nov 09 09:33:48 volumio volumio[7324]: Cannot find translation for sourceBandcamp Discover Nov 09 09:33:48 volumio volumio[7324]: Cannot find translation for sourceYouTube2 Nov 09 09:33:48 volumio volumio[7324]: Cannot find translation for sourceRadio Paradise Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::initPlayerControls Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 09 09:33:48 volumio volumio[7324]: Starting albumart workers Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: info: BOOT COMPLETED Nov 09 09:33:48 volumio volumio[7324]: [Metrics] CommandRouter: 5s 60.66ms Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:48 volumio volumio[7324]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Nov 09 09:33:48 volumio volumio[7324]: aplay: main:722: audio open error: No such file or directory Nov 09 09:33:48 volumio volumio[7324]: Cannot play startup sound Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:33:48 volumio volumio[7324]: Express server listening on port 3000 Nov 09 09:33:48 volumio volumio[7324]: [Metrics] WebUI: 5s 303.04ms Nov 09 09:33:48 volumio volumio[7324]: Volumio Calling Home Nov 09 09:33:48 volumio kernel: in count:805306, out count:805304 Nov 09 09:33:48 volumio kernel: reduce cnt:210, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:33:48 volumio volumio[7324]: info: CoreStateMachine::resetVolumioState Nov 09 09:33:48 volumio volumio[7324]: info: CoreStateMachine::getcurrentVolume Nov 09 09:33:48 volumio volumio[7324]: info: CoreCommandRouter::volumioRetrievevolume Nov 09 09:33:48 volumio volumio[7324]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 09 09:33:48 volumio volumio[7324]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:33:48 volumio volumio[7324]: ReferenceError: deferred is not defined Nov 09 09:33:48 volumio volumio[7324]: at ClientRequest.req.on (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Nov 09 09:33:48 volumio volumio[7324]: at emitOne (events.js:116:13) Nov 09 09:33:48 volumio volumio[7324]: at ClientRequest.emit (events.js:211:7) Nov 09 09:33:48 volumio volumio[7324]: at TLSSocket.socketErrorListener (_http_client.js:387:9) Nov 09 09:33:48 volumio volumio[7324]: at emitOne (events.js:116:13) Nov 09 09:33:48 volumio volumio[7324]: at TLSSocket.emit (events.js:211:7) Nov 09 09:33:48 volumio volumio[7324]: at emitErrorNT (internal/streams/destroy.js:64:8) Nov 09 09:33:48 volumio volumio[7324]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Nov 09 09:33:48 volumio volumio[7324]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 09 09:33:48 volumio volumio[7324]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:33:48 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:48 volumio kernel: add cnt:237, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:33:48 volumio sudo[7443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-09 09:32 Nov 09 09:33:48 volumio sudo[7443]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:49 volumio sudo[7443]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:49 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:49 volumio kernel: reduce cnt:211, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:33:49 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 09 09:33:49 volumio systemd[1]: Unit volumio.service entered failed state. Nov 09 09:33:49 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:49 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:49 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 09 09:33:49 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:49 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:49 volumio systemd[1]: Stopping Volumio Backend Module... Nov 09 09:33:49 volumio systemd[1]: Starting Volumio Backend Module... Nov 09 09:33:49 volumio systemd[1]: Started Volumio Backend Module. Nov 09 09:33:49 volumio kernel: in count:402647, out count:402651 Nov 09 09:33:49 volumio kernel: add cnt:238, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:33:49 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=30:c5:0f:a3:58:f4 status_code=1 Nov 09 09:33:49 volumio wpa_supplicant[6984]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Base_Linux" auth_failures=2 duration=23 reason=CONN_FAILED Nov 09 09:33:49 volumio kernel: connect failed event=0 e->status 4 e->reason 1 Nov 09 09:33:49 volumio kernel: CFG80211-ERROR) wl_bss_connect_done : Report connect result - connection failed Nov 09 09:33:49 volumio kernel: in count:402652, out count:402651 Nov 09 09:33:49 volumio kernel: reduce cnt:212, mpll1_rate:48999976, mpll2 rate:48999976 Nov 09 09:33:50 volumio kernel: in count:402647, out count:402652 Nov 09 09:33:50 volumio kernel: add cnt:239, mpll1_rate:48999976 mpll2 rate:48999317 Nov 09 09:33:50 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:50 volumio volumio[7468]: info: ----- Volumio2 ---- Nov 09 09:33:50 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:50 volumio volumio[7468]: info: ----- System startup ---- Nov 09 09:33:50 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:50 volumio volumio[7468]: info: MYVOLUMIO Environment detected Nov 09 09:33:50 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:50 volumio volumio[7468]: info: Plugin folders cleanup Nov 09 09:33:50 volumio volumio[7468]: info: Scanning into folder /volumio/app/plugins/ Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category audio_interface Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category miscellanea Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category music_service Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category plugins.json Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category system_controller Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category user_interface Nov 09 09:33:50 volumio volumio[7468]: info: Scanning into folder /data/plugins/ Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category accessory Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category audio_interface Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category music_service Nov 09 09:33:50 volumio volumio[7468]: info: Scanning category system_controller Nov 09 09:33:50 volumio volumio[7468]: info: Plugin folders cleanup completed Nov 09 09:33:50 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:50 volumio volumio[7468]: info: ----- Core plugins startup ---- Nov 09 09:33:50 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:50 volumio volumio[7468]: info: Loading plugins from folder /volumio/app/plugins/ Nov 09 09:33:50 volumio volumio[7468]: info: Adding plugin upnp to MyMusic Plugins Nov 09 09:33:50 volumio volumio[7468]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 09 09:33:50 volumio volumio[7468]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 09 09:33:50 volumio volumio[7468]: info: Loading plugins from folder /data/plugins/ Nov 09 09:33:50 volumio volumio[7468]: info: Loading plugin "system"... Nov 09 09:33:50 volumio volumio[7468]: info: Loading plugin "appearance"... Nov 09 09:33:51 volumio kernel: in count:805304, out count:805304 Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "network"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "services"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "alsa_controller"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "wizard"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "volumio_command_line_client"... Nov 09 09:33:51 volumio volumio[7468]: info: Plugin upnp is not enabled Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "my_music"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "mpd"... Nov 09 09:33:51 volumio kernel: in count:1207956, out count:1207956 Nov 09 09:33:51 volumio volumio[7468]: info: Plugin upnp_browser is not enabled Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "networkfs"... Nov 09 09:33:51 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "alarm-clock"... Nov 09 09:33:51 volumio sudo[7493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro lena.baselinux.net:/DATA/mmedia/music /mnt/NAS/lena_music Nov 09 09:33:51 volumio sudo[7493]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:51 volumio kernel: in count:1610608, out count:1610609 Nov 09 09:33:51 volumio kernel: add cnt:240, mpll1_rate:48999976 mpll2 rate:48999903 Nov 09 09:33:51 volumio sudo[7493]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:51 volumio volumio[7468]: info: Plugin airplay_emulation is not enabled Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "last_100"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "webradio"... Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "i2s_dacs"... Nov 09 09:33:51 volumio volumio[7468]: info: I2S DAC not set, start Auto-detection Nov 09 09:33:51 volumio volumio[7468]: info: Loading plugin "volumiodiscovery"... Nov 09 09:33:51 volumio volumio[7468]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:51 volumio node[7468]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:51 volumio node[7468]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:51 volumio node[7468]: *** WARNING *** For more information see Nov 09 09:33:51 volumio node[7468]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:51 volumio node[7468]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:51 volumio node[7468]: *** WARNING *** For more information see Nov 09 09:33:51 volumio volumio[7468]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:51 volumio volumio[7468]: *** WARNING *** For more information see Nov 09 09:33:51 volumio volumio[7468]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:33:51 volumio volumio[7468]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:33:51 volumio volumio[7468]: *** WARNING *** For more information see Nov 09 09:33:51 volumio volumio[7468]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 09 09:33:51 volumio volumio[7468]: Discovery: StartAdv! undefined Nov 09 09:33:51 volumio volumio[7468]: Discovery: Started advertising... Volumio - undefined Nov 09 09:33:52 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:33:52 volumio volumio[7468]: info: Plugin volsimpleequal is not enabled Nov 09 09:33:52 volumio volumio[7468]: info: Loading plugin "bandcamp"... Nov 09 09:33:52 volumio volumio[7468]: info: Loading plugin "youtube2"... Nov 09 09:33:52 volumio kernel: in count:402656, out count:402651 Nov 09 09:33:52 volumio kernel: reduce cnt:213, mpll1_rate:48999976, mpll2 rate:49000489 Nov 09 09:33:52 volumio kernel: in count:402650, out count:402652 Nov 09 09:33:52 volumio kernel: add cnt:241, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:33:53 volumio kernel: in count:402655, out count:402651 Nov 09 09:33:53 volumio kernel: reduce cnt:214, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:33:53 volumio kernel: in count:402650, out count:402651 Nov 09 09:33:53 volumio kernel: add cnt:242, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:33:53 volumio kernel: in count:402655, out count:402652 Nov 09 09:33:53 volumio kernel: reduce cnt:215, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "outputs"... Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "albumart"... Nov 09 09:33:54 volumio kernel: in count:402649, out count:402651 Nov 09 09:33:54 volumio kernel: add cnt:243, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:33:54 volumio volumio[7468]: info: Plugin example_plugin is not enabled Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "inputs"... Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "updater_comm"... Nov 09 09:33:54 volumio volumio[7468]: info: Plugin mpdemulation is not enabled Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "rest_api"... Nov 09 09:33:54 volumio systemd[1]: Starting Wifireconnect service... Nov 09 09:33:54 volumio systemd[1]: Started Wifireconnect service. Nov 09 09:33:54 volumio volumio[7525]: connect: Network is unreachable Nov 09 09:33:54 volumio systemd[1]: Stopping Wireless Services... Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "websocket"... Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "ir_controller"... Nov 09 09:33:54 volumio volumio[7468]: Forking 5 albumart workers Nov 09 09:33:54 volumio systemd[1]: Starting Wireless Services... Nov 09 09:33:54 volumio volumio[7468]: info: Applying required configuration parameters for plugin ir_controller Nov 09 09:33:54 volumio sudo[7533]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Nov 09 09:33:54 volumio sudo[7533]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:54 volumio sudo[7533]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "radio_paradise"... Nov 09 09:33:54 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:54 volumio kernel: reduce cnt:216, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:33:54 volumio volumio[7468]: info: Applying required configuration parameters for plugin radio_paradise Nov 09 09:33:54 volumio volumio[7468]: info: [1699522434760] [RadioParadise] API delay: 5 Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "backup_restore"... Nov 09 09:33:54 volumio wireless.js[7554]: Cleaning previous... Nov 09 09:33:54 volumio systemd[1]: Stopped hotspot.service. Nov 09 09:33:54 volumio volumio[7468]: info: Loading plugin "wifireconnect"... Nov 09 09:33:54 volumio sudo[7575]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 09 09:33:54 volumio sudo[7575]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:54 volumio sudo[7575]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:54 volumio sudo[7577]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 09 09:33:54 volumio sudo[7577]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:54 volumio kernel: dhd_stop: Enter ffffffc0971fb000 Nov 09 09:33:54 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Nov 09 09:33:54 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Nov 09 09:33:54 volumio kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Nov 09 09:33:54 volumio kernel: wl_android_wifi_off in 1 Nov 09 09:33:54 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Nov 09 09:33:54 volumio kernel: dhd_dbg_detach_pkt_monitor, 2204 Nov 09 09:33:54 volumio kernel: dhd_bus_devreset: == Power OFF == Nov 09 09:33:54 volumio kernel: bcmsdh_oob_intr_unregister: Enter Nov 09 09:33:54 volumio kernel: dhd_txglom_enable: enable 0 Nov 09 09:33:54 volumio kernel: dhd_conf_set_txglom_params: txglom_mode=copy Nov 09 09:33:54 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Nov 09 09:33:54 volumio kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Nov 09 09:33:54 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Nov 09 09:33:54 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Nov 09 09:33:54 volumio kernel: wifi_platform_set_power = 0 Nov 09 09:33:54 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Nov 09 09:33:54 volumio kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Nov 09 09:33:55 volumio volumio[7468]: Starting albumart workers Nov 09 09:33:55 volumio kernel: in count:402649, out count:402652 Nov 09 09:33:55 volumio kernel: add cnt:244, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:33:55 volumio volumio[7468]: info: ___________ START PLUGINS ___________ Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:55 volumio volumio[7468]: info: [1699522435090] CoreMusicLibrary::Adding element Last_100 Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:55 volumio volumio[7468]: info: [1699522435106] CoreMusicLibrary::Adding element Webradio Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:55 volumio volumio[7468]: info: [1699522435121] CoreMusicLibrary::Adding element Bandcamp Discover Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:55 volumio volumio[7468]: info: [youtube2-auth] Starting auth flow... Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:55 volumio volumio[7468]: info: [1699522435173] CoreMusicLibrary::Adding element YouTube2 Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:33:55 volumio volumio[7468]: info: [1699522435223] CoreMusicLibrary::Adding element Radio Paradise Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:55 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:55 volumio volumio[7468]: info: ----- MyVolumio plugins startup ---- Nov 09 09:33:55 volumio volumio[7468]: info: ------------------------------------------- Nov 09 09:33:55 volumio volumio[7468]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 09 09:33:55 volumio volumio[7468]: info: Loading i18n strings for locale es Nov 09 09:33:55 volumio kernel: wl_android_wifi_off out Nov 09 09:33:55 volumio kernel: wl_ext_iapsta_dettach_netdev: Enter Nov 09 09:33:55 volumio kernel: dhd_stop: Exit Nov 09 09:33:55 volumio sudo[7577]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:55 volumio sudo[7584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start wifireconnect.timer Nov 09 09:33:55 volumio sudo[7584]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:55 volumio sudo[7581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/lirc/hardware.conf Nov 09 09:33:55 volumio sudo[7581]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:55 volumio sudo[7581]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:55 volumio sudo[7582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/lirc/hardware.conf /etc/lirc/lircd.conf /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 09 09:33:55 volumio sudo[7582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:55 volumio volumio[7468]: Starting albumart workers Nov 09 09:33:55 volumio systemd[1]: Started Runs wifireconnect every 1min. Nov 09 09:33:55 volumio volumio[7468]: Starting albumart workers Nov 09 09:33:55 volumio sudo[7582]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:55 volumio sudo[7584]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:55 volumio volumio[7468]: Updating browse sources language Nov 09 09:33:55 volumio volumio[7468]: Cannot find translation for sourceBandcamp Discover Nov 09 09:33:55 volumio volumio[7468]: Cannot find translation for sourceYouTube2 Nov 09 09:33:55 volumio volumio[7468]: Cannot find translation for sourceRadio Paradise Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::initPlayerControls Nov 09 09:33:55 volumio volumio[7468]: Starting albumart workers Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 09 09:33:55 volumio volumio[7468]: Starting albumart workers Nov 09 09:33:55 volumio wireless.js[7554]: Stopped aP Nov 09 09:33:55 volumio wireless.js[7554]: Start wireless flow Nov 09 09:33:55 volumio wireless.js[7554]: Stopped hotspot (if there).. Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:55 volumio kernel: in count:402654, out count:402651 Nov 09 09:33:55 volumio kernel: reduce cnt:217, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:33:55 volumio volumio[7468]: info: BOOT COMPLETED Nov 09 09:33:55 volumio volumio[7468]: [Metrics] CommandRouter: 5s 150.11ms Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:33:55 volumio sudo[7599]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 09 09:33:55 volumio sudo[7599]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:55 volumio sudo[7599]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:55 volumio sudo[7602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 09 09:33:55 volumio sudo[7602]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:55 volumio sudo[7602]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:55 volumio volumio[7468]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Nov 09 09:33:55 volumio volumio[7468]: aplay: main:722: audio open error: No such file or directory Nov 09 09:33:55 volumio volumio[7468]: Cannot play startup sound Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:33:55 volumio volumio[7468]: Express server listening on port 3000 Nov 09 09:33:55 volumio wireless.js[7554]: DHCP IP Nov 09 09:33:55 volumio volumio[7468]: [Metrics] WebUI: 5s 410.24ms Nov 09 09:33:55 volumio wpa_supplicant[7610]: Successfully initialized wpa_supplicant Nov 09 09:33:55 volumio wireless.js[7554]: Start ap Nov 09 09:33:55 volumio kernel: dhd_open: Enter ffffffc0971fb000 Nov 09 09:33:55 volumio kernel: dhd_open : no mutex held. set lock Nov 09 09:33:55 volumio kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Nov 09 09:33:55 volumio kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Nov 09 09:33:55 volumio kernel: wl_android_wifi_on in 1 Nov 09 09:33:55 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 Nov 09 09:33:55 volumio kernel: wifi_platform_set_power = 1 Nov 09 09:33:55 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Nov 09 09:33:55 volumio kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Nov 09 09:33:55 volumio volumio[7468]: Volumio Calling Home Nov 09 09:33:55 volumio volumio[7468]: info: CoreStateMachine::resetVolumioState Nov 09 09:33:55 volumio volumio[7468]: info: CoreStateMachine::getcurrentVolume Nov 09 09:33:55 volumio volumio[7468]: info: CoreCommandRouter::volumioRetrievevolume Nov 09 09:33:55 volumio volumio[7468]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 09 09:33:55 volumio volumio[7468]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:33:55 volumio volumio[7468]: ReferenceError: deferred is not defined Nov 09 09:33:55 volumio volumio[7468]: at ClientRequest.req.on (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Nov 09 09:33:55 volumio volumio[7468]: at emitOne (events.js:116:13) Nov 09 09:33:55 volumio volumio[7468]: at ClientRequest.emit (events.js:211:7) Nov 09 09:33:55 volumio volumio[7468]: at TLSSocket.socketErrorListener (_http_client.js:387:9) Nov 09 09:33:55 volumio volumio[7468]: at emitOne (events.js:116:13) Nov 09 09:33:55 volumio volumio[7468]: at TLSSocket.emit (events.js:211:7) Nov 09 09:33:55 volumio volumio[7468]: at emitErrorNT (internal/streams/destroy.js:64:8) Nov 09 09:33:55 volumio volumio[7468]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Nov 09 09:33:55 volumio volumio[7468]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 09 09:33:55 volumio volumio[7468]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:33:55 volumio kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Nov 09 09:33:55 volumio kernel: in count:402648, out count:402652 Nov 09 09:33:55 volumio kernel: add cnt:245, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:33:56 volumio kernel: sdio_reset_comm(): Nov 09 09:33:56 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Nov 09 09:33:56 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Nov 09 09:33:56 volumio kernel: in count:402652, out count:402651 Nov 09 09:33:56 volumio kernel: reduce cnt:218, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:33:56 volumio kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Nov 09 09:33:56 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Nov 09 09:33:56 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Nov 09 09:33:56 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Nov 09 09:33:56 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Nov 09 09:33:56 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Nov 09 09:33:56 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Nov 09 09:33:56 volumio kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Nov 09 09:33:56 volumio kernel: sdio: queuing unknown CIS tuple 0x81 (9 bytes) Nov 09 09:33:56 volumio kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Nov 09 09:33:56 volumio kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Nov 09 09:33:56 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Nov 09 09:33:56 volumio kernel: meson-mmc: Data 1 aligned delay is 0 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 0, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 1, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 2, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 3, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 4, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 5, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 6, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 7, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 8, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: _aml_sd_emmc_execute_tuning() d1_dly 9, window start 0, size 5 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: clk 199999997 tuning start Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: best_win_start =2, best_win_size =4 Nov 09 09:33:56 volumio kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x42000 Nov 09 09:33:56 volumio kernel: meson-mmc: delay1:0x0, delay2:0x0 Nov 09 09:33:56 volumio kernel: sdioh_start: set sd_f2_blocksize 256 Nov 09 09:33:56 volumio kernel: dhd_bus_devreset: == WLAN ON == Nov 09 09:33:56 volumio kernel: F1 signature read @0x18000000=0x17294359 Nov 09 09:33:56 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Nov 09 09:33:56 volumio kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Nov 09 09:33:56 volumio kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Nov 09 09:33:56 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (184 bytes) open success Nov 09 09:33:56 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Nov 09 09:33:56 volumio kernel: dhd_conf_read_others: ccode = CN Nov 09 09:33:56 volumio kernel: dhd_conf_read_others: regrev = 38 Nov 09 09:33:56 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Nov 09 09:33:56 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Nov 09 09:33:56 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Nov 09 09:33:56 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Nov 09 09:33:56 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Nov 09 09:33:56 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Nov 09 09:33:56 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Nov 09 09:33:56 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Nov 09 09:33:56 volumio kernel: Final clm_path=/lib/firmware/brcm/clm.blob Nov 09 09:33:56 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt Nov 09 09:33:56 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Nov 09 09:33:56 volumio wireless.js[7554]: trying... Nov 09 09:33:56 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Nov 09 09:33:56 volumio kernel: [43B blob data] Nov 09 09:33:56 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Nov 09 09:33:56 volumio kernel: in count:402648, out count:402651 Nov 09 09:33:56 volumio kernel: add cnt:246, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:33:56 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Nov 09 09:33:56 volumio kernel: si_get_pmu_reg_addr: addrRET: 18000670 Nov 09 09:33:56 volumio kernel: bcmsdh_oob_intr_register: HW_OOB irq=69 flags=0x8 Nov 09 09:33:56 volumio kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Nov 09 09:33:56 volumio kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Nov 09 09:33:56 volumio kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Nov 09 09:33:56 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Nov 09 09:33:56 volumio kernel: Firmware up: op_mode=0x0405, MAC=18:93:7f:67:48:c4 Nov 09 09:33:56 volumio kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Nov 09 09:33:56 volumio kernel: dhd_txglom_enable: enable 1 Nov 09 09:33:56 volumio kernel: dhd_conf_set_txglom_params: txglom_mode=copy Nov 09 09:33:56 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Nov 09 09:33:56 volumio kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Nov 09 09:33:56 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Nov 09 09:33:56 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Nov 09 09:33:56 volumio kernel: dhd_pno_init: Support Android Location Service Nov 09 09:33:56 volumio kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Nov 09 09:33:56 volumio kernel: dhd_rtt_init : FTM is not supported Nov 09 09:33:56 volumio kernel: dhd_preinit_ioctls: SensorHub diabled 0 Nov 09 09:33:56 volumio kernel: dhd_preinit_ioctls failed to set ShubHub disable Nov 09 09:33:56 volumio kernel: failed to set WNM capabilities Nov 09 09:33:56 volumio kernel: dhd_conf_set_country: set country CN, revision 38 Nov 09 09:33:56 volumio kernel: Country code: CN (CN/38) Nov 09 09:33:56 volumio kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Nov 09 09:33:56 volumio kernel: wl_android_wifi_on: Success Nov 09 09:33:56 volumio kernel: dhd_open : the lock is released. Nov 09 09:33:56 volumio kernel: dhd_open: Exit ret=0 Nov 09 09:33:56 volumio sudo[7639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-09 09:32 Nov 09 09:33:56 volumio sudo[7639]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:56 volumio sudo[7614]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 09 09:33:56 volumio sudo[7614]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:56 volumio sudo[7641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:56 volumio dhcpcd[7644]: version 6.0.5 starting Nov 09 09:33:56 volumio sudo[7641]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:56 volumio sudo[7641]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:57 volumio dhcpcd[7644]: dummy0: soliciting a DHCP lease Nov 09 09:33:57 volumio dhcpcd[7644]: eth0: waiting for carrier Nov 09 09:33:57 volumio dhcpcd[7644]: wlan0: waiting for carrier Nov 09 09:33:57 volumio kernel: in count:402653, out count:402651 Nov 09 09:33:57 volumio kernel: reduce cnt:219, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:33:57 volumio sudo[7639]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:57 volumio systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE Nov 09 09:33:57 volumio systemd[1]: Unit volumio.service entered failed state. Nov 09 09:33:57 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:57 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:57 volumio kernel: in count:402647, out count:402651 Nov 09 09:33:57 volumio kernel: add cnt:247, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:33:57 volumio systemd[1]: volumio.service holdoff time over, scheduling restart. Nov 09 09:33:57 volumio systemd[1]: Starting dynamicswap service... Nov 09 09:33:57 volumio systemd[1]: Started dynamicswap service. Nov 09 09:33:57 volumio systemd[1]: Stopping Volumio Backend Module... Nov 09 09:33:57 volumio kernel: in count:402651, out count:402651 Nov 09 09:33:57 volumio wireless.js[7554]: trying... Nov 09 09:33:57 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:57] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:33:57 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:57] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:33:57 volumio volumio-remote-updater[4055]: [2023-11-09 09:33:57] [error] handle_connect error: Underlying Transport Error Nov 09 09:33:57 volumio sudo[7713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:57 volumio sudo[7713]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:57 volumio sudo[7713]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:58 volumio wpa_supplicant[7646]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:33:58 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:33:58 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:33:58 volumio kernel: in count:805304, out count:805304 Nov 09 09:33:58 volumio kernel: in count:1207956, out count:1207956 Nov 09 09:33:58 volumio wireless.js[7554]: trying... Nov 09 09:33:59 volumio sudo[7716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:33:59 volumio sudo[7716]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:33:59 volumio sudo[7716]: pam_unix(sudo:session): session closed for user root Nov 09 09:33:59 volumio kernel: in count:1610608, out count:1610609 Nov 09 09:33:59 volumio kernel: add cnt:248, mpll1_rate:48999976 mpll2 rate:48999976 Nov 09 09:33:59 volumio kernel: in count:402657, out count:402651 Nov 09 09:33:59 volumio kernel: reduce cnt:220, mpll1_rate:48999976, mpll2 rate:49000562 Nov 09 09:33:59 volumio kernel: in count:402651, out count:402652 Nov 09 09:33:59 volumio kernel: add cnt:249, mpll1_rate:48999976 mpll2 rate:48999903 Nov 09 09:34:00 volumio wireless.js[7554]: trying... Nov 09 09:34:00 volumio sudo[7719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:00 volumio sudo[7719]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:00 volumio sudo[7719]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:00 volumio kernel: in count:402656, out count:402652 Nov 09 09:34:00 volumio kernel: reduce cnt:221, mpll1_rate:48999976, mpll2 rate:49000489 Nov 09 09:34:00 volumio kernel: in count:402650, out count:402652 Nov 09 09:34:00 volumio kernel: add cnt:250, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:34:01 volumio wireless.js[7554]: trying... Nov 09 09:34:01 volumio sudo[7722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:01 volumio sudo[7722]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:01 volumio sudo[7722]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:01 volumio kernel: in count:402655, out count:402651 Nov 09 09:34:01 volumio kernel: reduce cnt:222, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:34:01 volumio kernel: in count:402650, out count:402652 Nov 09 09:34:01 volumio kernel: add cnt:251, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:34:01 volumio kernel: in count:402655, out count:402651 Nov 09 09:34:01 volumio kernel: reduce cnt:223, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:34:02 volumio wireless.js[7554]: trying... Nov 09 09:34:02 volumio sudo[7725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:02 volumio sudo[7725]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:02 volumio sudo[7725]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:02 volumio kernel: in count:402649, out count:402652 Nov 09 09:34:02 volumio kernel: add cnt:252, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:34:02 volumio kernel: in count:402654, out count:402652 Nov 09 09:34:02 volumio kernel: reduce cnt:224, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:34:03 volumio kernel: in count:402649, out count:402651 Nov 09 09:34:03 volumio kernel: add cnt:253, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:34:03 volumio wireless.js[7554]: trying... Nov 09 09:34:03 volumio sudo[7730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:03 volumio sudo[7730]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:03 volumio sudo[7730]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:03 volumio kernel: in count:402654, out count:402652 Nov 09 09:34:03 volumio kernel: reduce cnt:225, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:34:03 volumio kernel: wl_iw_event: Link UP with 30:c5:0f:a3:58:f4 Nov 09 09:34:03 volumio kernel: wl_bss_connect_done succeeded with 30:c5:0f:a3:58:f4 Nov 09 09:34:03 volumio kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Nov 09 09:34:03 volumio wpa_supplicant[7646]: wlan0: Associated with 30:c5:0f:a3:58:f4 Nov 09 09:34:03 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RO Nov 09 09:34:03 volumio kernel: wl_bss_connect_done succeeded with 30:c5:0f:a3:58:f4 vndr_oui: AC-85-3D 00-E0-FC Nov 09 09:34:03 volumio kernel: in count:402648, out count:402651 Nov 09 09:34:03 volumio kernel: add cnt:254, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:34:04 volumio wireless.js[7554]: trying... Nov 09 09:34:04 volumio sudo[7736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:04 volumio sudo[7736]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:04 volumio sudo[7736]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:04 volumio kernel: in count:402653, out count:402652 Nov 09 09:34:04 volumio kernel: reduce cnt:226, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:34:04 volumio kernel: in count:402648, out count:402651 Nov 09 09:34:04 volumio kernel: add cnt:255, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:34:05 volumio kernel: in count:402652, out count:402652 Nov 09 09:34:05 volumio wireless.js[7554]: trying... Nov 09 09:34:05 volumio sudo[7739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:05 volumio sudo[7739]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:05 volumio sudo[7739]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:05 volumio kernel: in count:805306, out count:805304 Nov 09 09:34:05 volumio kernel: reduce cnt:227, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:34:05 volumio kernel: in count:402646, out count:402651 Nov 09 09:34:05 volumio kernel: add cnt:256, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:34:06 volumio wireless.js[7554]: trying... Nov 09 09:34:06 volumio sudo[7742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:06 volumio sudo[7742]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:06 volumio sudo[7742]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:06 volumio kernel: in count:402652, out count:402652 Nov 09 09:34:06 volumio kernel: in count:805304, out count:805304 Nov 09 09:34:07 volumio kernel: in count:1207956, out count:1207957 Nov 09 09:34:07 volumio kernel: add cnt:257, mpll1_rate:48999976 mpll2 rate:48999976 Nov 09 09:34:07 volumio wireless.js[7554]: trying... Nov 09 09:34:07 volumio sudo[7745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:07 volumio sudo[7745]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:07 volumio sudo[7745]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:07 volumio kernel: in count:402656, out count:402651 Nov 09 09:34:07 volumio kernel: reduce cnt:228, mpll1_rate:48999976, mpll2 rate:49000562 Nov 09 09:34:07 volumio kernel: in count:402651, out count:402651 Nov 09 09:34:07 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:07] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:34:07 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:34:07 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:07] [error] handle_connect error: Underlying Transport Error Nov 09 09:34:08 volumio wireless.js[7554]: trying... Nov 09 09:34:08 volumio sudo[7748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:08 volumio sudo[7748]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:08 volumio sudo[7748]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:08 volumio kernel: in count:805303, out count:805304 Nov 09 09:34:08 volumio kernel: add cnt:258, mpll1_rate:48999976 mpll2 rate:48999903 Nov 09 09:34:08 volumio kernel: in count:402656, out count:402651 Nov 09 09:34:08 volumio kernel: reduce cnt:229, mpll1_rate:48999976, mpll2 rate:49000489 Nov 09 09:34:09 volumio kernel: in count:402651, out count:402651 Nov 09 09:34:09 volumio wireless.js[7554]: trying... Nov 09 09:34:09 volumio sudo[7751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:09 volumio sudo[7751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:09 volumio sudo[7751]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:09 volumio kernel: in count:805302, out count:805304 Nov 09 09:34:09 volumio kernel: add cnt:259, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:34:09 volumio kernel: in count:402655, out count:402652 Nov 09 09:34:09 volumio kernel: reduce cnt:230, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:34:10 volumio kernel: in count:402650, out count:402652 Nov 09 09:34:10 volumio kernel: add cnt:260, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:34:10 volumio wireless.js[7554]: trying... Nov 09 09:34:10 volumio sudo[7754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:10 volumio sudo[7754]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:10 volumio sudo[7754]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:10 volumio kernel: in count:402655, out count:402652 Nov 09 09:34:10 volumio kernel: reduce cnt:231, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:34:11 volumio kernel: in count:402649, out count:402651 Nov 09 09:34:11 volumio kernel: add cnt:261, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:34:11 volumio wireless.js[7554]: trying... Nov 09 09:34:11 volumio sudo[7757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:11 volumio sudo[7757]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:11 volumio sudo[7757]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:11 volumio kernel: in count:402654, out count:402652 Nov 09 09:34:11 volumio kernel: reduce cnt:232, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:34:11 volumio kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_DISASSOC_IND Nov 09 09:34:11 volumio kernel: link down if wlan0 may call cfg80211_disconnected. event : 12, reason=2 from 30:c5:0f:a3:58:f4 Nov 09 09:34:11 volumio kernel: dhd_dbg_stop_pkt_monitor, 1963 Nov 09 09:34:11 volumio kernel: wl_iw_event: Link Down with 30:c5:0f:a3:58:f4, reason=2 Nov 09 09:34:11 volumio kernel: CFG80211-ERROR) wl_cfg80211_tdls_config : No change in tdls state. Do nothing. tdls_enable:1 Nov 09 09:34:11 volumio kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK Nov 09 09:34:11 volumio kernel: CFG80211-ERROR) wl_cfg80211_tdls_config : No change in tdls state. Do nothing. tdls_enable:1 Nov 09 09:34:11 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-DISCONNECTED bssid=30:c5:0f:a3:58:f4 reason=2 Nov 09 09:34:11 volumio wpa_supplicant[7646]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Nov 09 09:34:11 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Base_Linux" auth_failures=1 duration=10 reason=WRONG_KEY Nov 09 09:34:11 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 09 09:34:11 volumio kernel: in count:402649, out count:402651 Nov 09 09:34:11 volumio kernel: add cnt:262, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:34:12 volumio kernel: in count:402654, out count:402651 Nov 09 09:34:12 volumio kernel: reduce cnt:233, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:34:12 volumio wireless.js[7554]: trying... Nov 09 09:34:12 volumio sudo[7763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:12 volumio sudo[7763]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:12 volumio sudo[7763]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:12 volumio kernel: in count:402648, out count:402651 Nov 09 09:34:12 volumio kernel: add cnt:263, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:34:13 volumio kernel: in count:402652, out count:402652 Nov 09 09:34:13 volumio wireless.js[7554]: trying... Nov 09 09:34:13 volumio sudo[7766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:13 volumio sudo[7766]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:13 volumio sudo[7766]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:13 volumio kernel: in count:805306, out count:805304 Nov 09 09:34:13 volumio kernel: reduce cnt:234, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:34:13 volumio kernel: in count:402648, out count:402652 Nov 09 09:34:13 volumio kernel: add cnt:264, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:34:14 volumio kernel: in count:402652, out count:402652 Nov 09 09:34:14 volumio wireless.js[7554]: trying... Nov 09 09:34:14 volumio sudo[7769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:14 volumio sudo[7769]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:14 volumio sudo[7769]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:14 volumio kernel: in count:805306, out count:805304 Nov 09 09:34:14 volumio kernel: reduce cnt:235, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:34:15 volumio kernel: in count:402646, out count:402652 Nov 09 09:34:15 volumio kernel: add cnt:265, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:34:15 volumio wireless.js[7554]: trying... Nov 09 09:34:15 volumio sudo[7772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:15 volumio sudo[7772]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:15 volumio sudo[7772]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:15 volumio kernel: in count:402651, out count:402651 Nov 09 09:34:16 volumio kernel: in count:805303, out count:805303 Nov 09 09:34:16 volumio kernel: in count:1207956, out count:1207956 Nov 09 09:34:16 volumio wireless.js[7554]: trying... Nov 09 09:34:16 volumio sudo[7775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 09 09:34:16 volumio sudo[7775]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:16 volumio sudo[7775]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:16 volumio kernel: in count:1610608, out count:1610608 Nov 09 09:34:17 volumio kernel: in count:2013261, out count:2013261 Nov 09 09:34:17 volumio kernel: in count:2415913, out count:2415913 Nov 09 09:34:17 volumio wireless.js[7554]: Overtime, starting plan B Nov 09 09:34:17 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:17] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:34:17 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:34:17 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:17] [error] handle_connect error: Underlying Transport Error Nov 09 09:34:18 volumio kernel: in count:2818565, out count:2818565 Nov 09 09:34:18 volumio kernel: in count:3221218, out count:3221218 Nov 09 09:34:18 volumio kernel: in count:3623870, out count:3623870 Nov 09 09:34:19 volumio kernel: in count:4026523, out count:4026523 Nov 09 09:34:19 volumio kernel: in count:4429175, out count:4429175 Nov 09 09:34:20 volumio kernel: in count:4831827, out count:4831827 Nov 09 09:34:20 volumio kernel: in count:5234480, out count:5234480 Nov 09 09:34:20 volumio kernel: in count:5637132, out count:5637132 Nov 09 09:34:21 volumio kernel: in count:6039785, out count:6039785 Nov 09 09:34:21 volumio kernel: in count:6442437, out count:6442437 Nov 09 09:34:22 volumio kernel: in count:6845089, out count:6845089 Nov 09 09:34:22 volumio kernel: in count:7247742, out count:7247742 Nov 09 09:34:22 volumio kernel: in count:7650394, out count:7650394 Nov 09 09:34:23 volumio kernel: in count:8053047, out count:8053047 Nov 09 09:34:23 volumio kernel: in count:8455699, out count:8455699 Nov 09 09:34:24 volumio kernel: in count:8858351, out count:8858351 Nov 09 09:34:24 volumio kernel: in count:9261004, out count:9261004 Nov 09 09:34:24 volumio kernel: in count:9663656, out count:9663656 Nov 09 09:34:25 volumio kernel: in count:10066309, out count:10066309 Nov 09 09:34:25 volumio kernel: in count:10468961, out count:10468961 Nov 09 09:34:26 volumio kernel: in count:10871613, out count:10871613 Nov 09 09:34:26 volumio kernel: in count:11274266, out count:11274266 Nov 09 09:34:26 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Base_Linux" Nov 09 09:34:26 volumio wpa_supplicant[7646]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:34:26 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:34:26 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:34:26 volumio kernel: in count:11676918, out count:11676918 Nov 09 09:34:27 volumio dhcpcd[7644]: timed out Nov 09 09:34:27 volumio dhcpcd[7644]: allowing 8 seconds for IPv4LL timeout Nov 09 09:34:27 volumio kernel: in count:12079571, out count:12079571 Nov 09 09:34:27 volumio kernel: in count:12482223, out count:12482223 Nov 09 09:34:27 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:27] [info] asio async_connect error: system:111 (Connection refused) Nov 09 09:34:27 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 09 09:34:27 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:27] [error] handle_connect error: Underlying Transport Error Nov 09 09:34:28 volumio kernel: in count:12884875, out count:12884875 Nov 09 09:34:28 volumio kernel: in count:13287528, out count:13287528 Nov 09 09:34:28 volumio kernel: in count:13690180, out count:13690180 Nov 09 09:34:29 volumio kernel: in count:14092832, out count:14092833 Nov 09 09:34:29 volumio kernel: add cnt:266, mpll1_rate:48999976 mpll2 rate:48999976 Nov 09 09:34:29 volumio kernel: in count:402656, out count:402651 Nov 09 09:34:29 volumio kernel: reduce cnt:236, mpll1_rate:48999976, mpll2 rate:49000562 Nov 09 09:34:30 volumio kernel: in count:402652, out count:402651 Nov 09 09:34:30 volumio kernel: reduce cnt:237, mpll1_rate:48999976, mpll2 rate:48999903 Nov 09 09:34:30 volumio kernel: in count:402646, out count:402651 Nov 09 09:34:30 volumio kernel: add cnt:267, mpll1_rate:48999976 mpll2 rate:48999244 Nov 09 09:34:30 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=30:c5:0f:a3:58:f4 status_code=1 Nov 09 09:34:30 volumio kernel: connect failed event=0 e->status 4 e->reason 1 Nov 09 09:34:30 volumio kernel: CFG80211-ERROR) wl_bss_connect_done : Report connect result - connection failed Nov 09 09:34:30 volumio kernel: in count:402650, out count:402652 Nov 09 09:34:30 volumio kernel: add cnt:268, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:34:31 volumio kernel: in count:402655, out count:402652 Nov 09 09:34:31 volumio kernel: reduce cnt:238, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:34:31 volumio kernel: in count:402650, out count:402651 Nov 09 09:34:31 volumio kernel: add cnt:269, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:34:32 volumio kernel: in count:402655, out count:402652 Nov 09 09:34:32 volumio kernel: reduce cnt:239, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:34:32 volumio kernel: in count:402649, out count:402652 Nov 09 09:34:32 volumio kernel: add cnt:270, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:34:32 volumio kernel: in count:402654, out count:402652 Nov 09 09:34:32 volumio kernel: reduce cnt:240, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:34:33 volumio kernel: in count:402649, out count:402651 Nov 09 09:34:33 volumio kernel: add cnt:271, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:34:33 volumio kernel: in count:402654, out count:402652 Nov 09 09:34:33 volumio kernel: reduce cnt:241, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:34:33 volumio wpa_supplicant[7646]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:34:33 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:34:33 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:34:34 volumio kernel: in count:402648, out count:402651 Nov 09 09:34:34 volumio kernel: add cnt:272, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:34:34 volumio kernel: in count:402653, out count:402651 Nov 09 09:34:34 volumio kernel: reduce cnt:242, mpll1_rate:48999976, mpll2 rate:49000123 Nov 09 09:34:34 volumio kernel: in count:402648, out count:402652 Nov 09 09:34:34 volumio kernel: add cnt:273, mpll1_rate:48999976 mpll2 rate:48999463 Nov 09 09:34:35 volumio dhcpcd[7644]: timed out Nov 09 09:34:35 volumio dhcpcd[7644]: forked to background, child pid 7779 Nov 09 09:34:35 volumio sudo[7614]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:35 volumio systemd[1]: Started Wireless Services. Nov 09 09:34:35 volumio systemd[1]: Starting Volumio Backend Module... Nov 09 09:34:35 volumio systemd[1]: Started Volumio Backend Module. Nov 09 09:34:35 volumio kernel: in count:402653, out count:402651 Nov 09 09:34:35 volumio kernel: reduce cnt:243, mpll1_rate:48999976, mpll2 rate:49000050 Nov 09 09:34:35 volumio kernel: in count:402646, out count:402652 Nov 09 09:34:35 volumio kernel: add cnt:274, mpll1_rate:48999976 mpll2 rate:48999390 Nov 09 09:34:35 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:35 volumio volumio[7781]: info: ----- Volumio2 ---- Nov 09 09:34:35 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:35 volumio volumio[7781]: info: ----- System startup ---- Nov 09 09:34:35 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:36 volumio volumio[7781]: info: MYVOLUMIO Environment detected Nov 09 09:34:36 volumio kernel: in count:402652, out count:402651 Nov 09 09:34:36 volumio kernel: reduce cnt:244, mpll1_rate:48999976, mpll2 rate:48999976 Nov 09 09:34:36 volumio volumio[7781]: info: Plugin folders cleanup Nov 09 09:34:36 volumio volumio[7781]: info: Scanning into folder /volumio/app/plugins/ Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category audio_interface Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category miscellanea Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category music_service Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category plugins.json Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category system_controller Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category user_interface Nov 09 09:34:36 volumio volumio[7781]: info: Scanning into folder /data/plugins/ Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category accessory Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category audio_interface Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category music_service Nov 09 09:34:36 volumio volumio[7781]: info: Scanning category system_controller Nov 09 09:34:36 volumio volumio[7781]: info: Plugin folders cleanup completed Nov 09 09:34:36 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:36 volumio volumio[7781]: info: ----- Core plugins startup ---- Nov 09 09:34:36 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugins from folder /volumio/app/plugins/ Nov 09 09:34:36 volumio volumio[7781]: info: Adding plugin upnp to MyMusic Plugins Nov 09 09:34:36 volumio volumio[7781]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 09 09:34:36 volumio volumio[7781]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugins from folder /data/plugins/ Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugin "system"... Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugin "appearance"... Nov 09 09:34:36 volumio kernel: in count:402646, out count:402651 Nov 09 09:34:36 volumio kernel: add cnt:275, mpll1_rate:48999976 mpll2 rate:48999317 Nov 09 09:34:36 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=30:c5:0f:a3:58:f4 status_code=1 Nov 09 09:34:36 volumio kernel: connect failed event=0 e->status 4 e->reason 1 Nov 09 09:34:36 volumio kernel: CFG80211-ERROR) wl_bss_connect_done : Report connect result - connection failed Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugin "network"... Nov 09 09:34:36 volumio kernel: in count:402651, out count:402651 Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugin "services"... Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugin "alsa_controller"... Nov 09 09:34:36 volumio volumio[7781]: info: Loading plugin "wizard"... Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "volumio_command_line_client"... Nov 09 09:34:37 volumio volumio[7781]: info: Plugin upnp is not enabled Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "my_music"... Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "mpd"... Nov 09 09:34:37 volumio volumio[7781]: info: Plugin upnp_browser is not enabled Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "networkfs"... Nov 09 09:34:37 volumio kernel: in count:805304, out count:805303 Nov 09 09:34:37 volumio kernel: reduce cnt:245, mpll1_rate:48999976, mpll2 rate:48999903 Nov 09 09:34:37 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "alarm-clock"... Nov 09 09:34:37 volumio sudo[7805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto,ro lena.baselinux.net:/DATA/mmedia/music /mnt/NAS/lena_music Nov 09 09:34:37 volumio sudo[7805]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:37 volumio sudo[7805]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:37 volumio volumio[7781]: info: Plugin airplay_emulation is not enabled Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "last_100"... Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "webradio"... Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "i2s_dacs"... Nov 09 09:34:37 volumio volumio[7781]: info: I2S DAC not set, start Auto-detection Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "volumiodiscovery"... Nov 09 09:34:37 volumio volumio[7781]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:34:37 volumio node[7781]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 09 09:34:37 volumio node[7781]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:34:37 volumio node[7781]: *** WARNING *** For more information see Nov 09 09:34:37 volumio node[7781]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:34:37 volumio node[7781]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:34:37 volumio node[7781]: *** WARNING *** For more information see Nov 09 09:34:37 volumio volumio[7781]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:34:37 volumio volumio[7781]: *** WARNING *** For more information see Nov 09 09:34:37 volumio volumio[7781]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 09 09:34:37 volumio volumio[7781]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 09 09:34:37 volumio volumio[7781]: *** WARNING *** For more information see Nov 09 09:34:37 volumio volumio[7781]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 09 09:34:37 volumio volumio[7781]: Discovery: StartAdv! undefined Nov 09 09:34:37 volumio volumio[7781]: Discovery: Started advertising... Volumio - undefined Nov 09 09:34:37 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 09 09:34:37 volumio volumio[7781]: info: Plugin volsimpleequal is not enabled Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "bandcamp"... Nov 09 09:34:37 volumio kernel: in count:402645, out count:402651 Nov 09 09:34:37 volumio kernel: add cnt:276, mpll1_rate:48999976 mpll2 rate:48999244 Nov 09 09:34:37 volumio volumio[7781]: info: Loading plugin "youtube2"... Nov 09 09:34:37 volumio volumio-remote-updater[4055]: [2023-11-09 09:34:37] [connect] Successful connection Nov 09 09:34:38 volumio kernel: in count:402651, out count:402652 Nov 09 09:34:38 volumio kernel: add cnt:277, mpll1_rate:48999976 mpll2 rate:48999830 Nov 09 09:34:38 volumio kernel: in count:402655, out count:402652 Nov 09 09:34:38 volumio kernel: reduce cnt:246, mpll1_rate:48999976, mpll2 rate:49000416 Nov 09 09:34:38 volumio kernel: in count:402650, out count:402651 Nov 09 09:34:38 volumio kernel: add cnt:278, mpll1_rate:48999976 mpll2 rate:48999756 Nov 09 09:34:39 volumio wpa_supplicant[7646]: wlan0: Trying to associate with 30:c5:0f:a3:58:f4 (SSID='Base_Linux' freq=2462 MHz) Nov 09 09:34:39 volumio kernel: Connecting with 30:c5:0f:a3:58:f4 ssid "Base_Linux", len (10) channel=11 Nov 09 09:34:39 volumio kernel: dhd_dbg_start_pkt_monitor, 1724 Nov 09 09:34:39 volumio kernel: in count:402656, out count:402652 Nov 09 09:34:39 volumio kernel: reduce cnt:247, mpll1_rate:48999976, mpll2 rate:49000343 Nov 09 09:34:39 volumio kernel: in count:402649, out count:402651 Nov 09 09:34:39 volumio kernel: add cnt:279, mpll1_rate:48999976 mpll2 rate:48999683 Nov 09 09:34:39 volumio volumio[7781]: info: Loading plugin "outputs"... Nov 09 09:34:39 volumio volumio[7781]: info: Loading plugin "albumart"... Nov 09 09:34:39 volumio volumio[7781]: info: Plugin example_plugin is not enabled Nov 09 09:34:39 volumio volumio[7781]: info: Loading plugin "inputs"... Nov 09 09:34:39 volumio volumio[7781]: info: Loading plugin "updater_comm"... Nov 09 09:34:40 volumio volumio[7781]: info: Plugin mpdemulation is not enabled Nov 09 09:34:40 volumio volumio[7781]: info: Loading plugin "rest_api"... Nov 09 09:34:40 volumio volumio[7781]: info: Loading plugin "websocket"... Nov 09 09:34:40 volumio volumio[7781]: info: Loading plugin "ir_controller"... Nov 09 09:34:40 volumio volumio[7781]: Forking 5 albumart workers Nov 09 09:34:40 volumio volumio[7781]: info: Applying required configuration parameters for plugin ir_controller Nov 09 09:34:40 volumio kernel: in count:402654, out count:402652 Nov 09 09:34:40 volumio kernel: reduce cnt:248, mpll1_rate:48999976, mpll2 rate:49000269 Nov 09 09:34:40 volumio volumio[7781]: info: Loading plugin "radio_paradise"... Nov 09 09:34:40 volumio volumio[7781]: info: Applying required configuration parameters for plugin radio_paradise Nov 09 09:34:40 volumio volumio[7781]: info: [1699522480396] [RadioParadise] API delay: 5 Nov 09 09:34:40 volumio volumio[7781]: info: Loading plugin "backup_restore"... Nov 09 09:34:40 volumio volumio[7781]: info: Loading plugin "wifireconnect"... Nov 09 09:34:40 volumio kernel: in count:402649, out count:402651 Nov 09 09:34:40 volumio kernel: add cnt:280, mpll1_rate:48999976 mpll2 rate:48999610 Nov 09 09:34:40 volumio volumio[7781]: Starting albumart workers Nov 09 09:34:40 volumio volumio[7781]: Starting albumart workers Nov 09 09:34:40 volumio volumio[7781]: info: ___________ START PLUGINS ___________ Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:34:40 volumio volumio[7781]: info: [1699522480710] CoreMusicLibrary::Adding element Last_100 Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:34:40 volumio volumio[7781]: info: [1699522480720] CoreMusicLibrary::Adding element Webradio Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:34:40 volumio volumio[7781]: info: [1699522480740] CoreMusicLibrary::Adding element Bandcamp Discover Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:34:40 volumio volumio[7781]: info: [youtube2-auth] Starting auth flow... Nov 09 09:34:40 volumio volumio[7781]: Starting albumart workers Nov 09 09:34:40 volumio volumio[7781]: Starting albumart workers Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:34:40 volumio volumio[7781]: info: [1699522480824] CoreMusicLibrary::Adding element YouTube2 Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:34:40 volumio sudo[7852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/lirc/hardware.conf Nov 09 09:34:40 volumio sudo[7852]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 09 09:34:40 volumio volumio[7781]: info: [1699522480887] CoreMusicLibrary::Adding element Radio Paradise Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:34:40 volumio sudo[7852]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:40 volumio sudo[7861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/lirc/hardware.conf /etc/lirc/lircd.conf /etc/lirc/lircmd.conf /etc/lirc/lircrc Nov 09 09:34:40 volumio sudo[7861]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:40 volumio sudo[7861]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:40 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:40 volumio volumio[7781]: info: ----- MyVolumio plugins startup ---- Nov 09 09:34:40 volumio volumio[7781]: info: ------------------------------------------- Nov 09 09:34:40 volumio volumio[7781]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 09 09:34:40 volumio volumio[7781]: info: Loading i18n strings for locale es Nov 09 09:34:40 volumio sudo[7865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start wifireconnect.timer Nov 09 09:34:40 volumio volumio[7781]: Updating browse sources language Nov 09 09:34:40 volumio volumio[7781]: Cannot find translation for sourceBandcamp Discover Nov 09 09:34:40 volumio sudo[7865]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:40 volumio volumio[7781]: Cannot find translation for sourceYouTube2 Nov 09 09:34:40 volumio volumio[7781]: Cannot find translation for sourceRadio Paradise Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 09 09:34:40 volumio systemd[1]: Started Runs wifireconnect every 1min. Nov 09 09:34:40 volumio sudo[7865]: pam_unix(sudo:session): session closed for user root Nov 09 09:34:40 volumio kernel: in count:402654, out count:402651 Nov 09 09:34:40 volumio kernel: reduce cnt:249, mpll1_rate:48999976, mpll2 rate:49000196 Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:34:40 volumio volumio[7781]: info: CoreCommandRouter::initPlayerControls Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 09 09:34:41 volumio volumio[7781]: Starting albumart workers Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: info: BOOT COMPLETED Nov 09 09:34:41 volumio volumio[7781]: [Metrics] CommandRouter: 5s 68.51ms Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 09 09:34:41 volumio volumio[7781]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Nov 09 09:34:41 volumio volumio[7781]: aplay: main:722: audio open error: No such file or directory Nov 09 09:34:41 volumio volumio[7781]: Cannot play startup sound Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::Close All Modals sent Nov 09 09:34:41 volumio volumio[7781]: Express server listening on port 3000 Nov 09 09:34:41 volumio volumio[7781]: [Metrics] WebUI: 5s 311.89ms Nov 09 09:34:41 volumio volumio[7781]: Volumio Calling Home Nov 09 09:34:41 volumio kernel: wl_iw_event: Link UP with 30:c5:0f:a3:58:f4 Nov 09 09:34:41 volumio kernel: wl_bss_connect_done succeeded with 30:c5:0f:a3:58:f4 Nov 09 09:34:41 volumio kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Nov 09 09:34:41 volumio wpa_supplicant[7646]: wlan0: Associated with 30:c5:0f:a3:58:f4 Nov 09 09:34:41 volumio volumio[7781]: info: CoreStateMachine::resetVolumioState Nov 09 09:34:41 volumio volumio[7781]: info: CoreStateMachine::getcurrentVolume Nov 09 09:34:41 volumio volumio[7781]: info: CoreCommandRouter::volumioRetrievevolume Nov 09 09:34:41 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RO Nov 09 09:34:41 volumio volumio[7781]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 09 09:34:41 volumio volumio[7781]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:34:41 volumio volumio[7781]: ReferenceError: deferred is not defined Nov 09 09:34:41 volumio volumio[7781]: at ClientRequest.req.on (/data/plugins/music_service/youtube2/lib/core/gapi/auth.js:155:13) Nov 09 09:34:41 volumio volumio[7781]: at emitOne (events.js:116:13) Nov 09 09:34:41 volumio volumio[7781]: at ClientRequest.emit (events.js:211:7) Nov 09 09:34:41 volumio volumio[7781]: at TLSSocket.socketErrorListener (_http_client.js:387:9) Nov 09 09:34:41 volumio volumio[7781]: at emitOne (events.js:116:13) Nov 09 09:34:41 volumio volumio[7781]: at TLSSocket.emit (events.js:211:7) Nov 09 09:34:41 volumio volumio[7781]: at emitErrorNT (internal/streams/destroy.js:64:8) Nov 09 09:34:41 volumio volumio[7781]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Nov 09 09:34:41 volumio volumio[7781]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 09 09:34:41 volumio volumio[7781]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 09 09:34:41 volumio kernel: wl_bss_connect_done succeeded with 30:c5:0f:a3:58:f4 vndr_oui: AC-85-3D 00-E0-FC Nov 09 09:34:41 volumio wpa_supplicant[7646]: wlan0: WPA: Key negotiation completed with 30:c5:0f:a3:58:f4 [PTK=CCMP GTK=TKIP] Nov 09 09:34:41 volumio wpa_supplicant[7646]: wlan0: CTRL-EVENT-CONNECTED - Connection to 30:c5:0f:a3:58:f4 completed [id=0 id_str=] Nov 09 09:34:41 volumio dhcpcd[7779]: wlan0: carrier acquired Nov 09 09:34:41 volumio dhcpcd[7779]: wlan0: soliciting an IPv6 router Nov 09 09:34:41 volumio dhcpcd[7779]: wlan0: using static address 192.168.10.105 Nov 09 09:34:41 volumio avahi-daemon[4083]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.105. Nov 09 09:34:41 volumio dhcpcd[7779]: wlan0: adding host route to 192.168.10.105 via 127.0.0.1 Nov 09 09:34:41 volumio avahi-daemon[4083]: New relevant interface wlan0.IPv4 for mDNS. Nov 09 09:34:41 volumio avahi-daemon[4083]: Registering new address record for 192.168.10.105 on wlan0.IPv4. Nov 09 09:34:41 volumio dhcpcd[7779]: wlan0: adding route to 192.168.10.0/24 Nov 09 09:34:41 volumio dhcpcd[7779]: wlan0: adding default route via 192.168.10.100 Nov 09 09:34:41 volumio kernel: in count:402648, out count:402651 Nov 09 09:34:41 volumio kernel: add cnt:281, mpll1_rate:48999976 mpll2 rate:48999537 Nov 09 09:34:41 volumio sudo[7933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-09 09:33 Nov 09 09:34:41 volumio sudo[7933]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 09 09:34:41 volumio kernel: in count:402653, out count:402652 Nov 09 09:34:41 volumio kernel: reduce cnt:250, mpll1_rate:48999976, mpll2 rate:49000123 PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b932dfa86bb2a8751f6a33e2d2c53a3d4f39440a" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="9baf06b722503c872bcb7e7c52470e6abc938a93" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Apr 13 21:46:37 CEST 2020" VOLUMIO_VERSION="2.748" VOLUMIO_HARDWARE="kvim3l" VOLUMIO_HASH="f4565b0bce263ead83772de5f9ce903d"