-- Logs begin at Sat 2025-03-01 09:55:15 KST, end at Sat 2025-03-01 09:57:03 KST. -- Mar 01 09:56:02 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:02 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:02 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:07 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:07 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:07 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:12 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:12 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:12 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:17 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:17 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:17 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:22 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:22 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:22 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:23 volumio ntpd[940]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 01 09:56:24 volumio ntpd[940]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 01 09:56:25 volumio ntpd[940]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 01 09:56:26 volumio ntpd[940]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 01 09:56:27 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:27 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:27 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:29 volumio dhcpcd[845]: eth0: carrier acquired Mar 01 09:56:29 volumio kernel: r8169 0000:02:00.0 eth0: Link is Up - 100Mbps/Full - flow control rx/tx Mar 01 09:56:29 volumio dhcpcd[845]: eth0: IAID 06:45:4d:e0 Mar 01 09:56:30 volumio dhcpcd[845]: eth0: rebinding lease of 192.168.1.22 Mar 01 09:56:30 volumio dhcpcd[845]: eth0: soliciting an IPv6 router Mar 01 09:56:30 volumio ifplugd(eth0)[941]: Link beat detected. Mar 01 09:56:30 volumio ifplugd(eth0)[941]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 01 09:56:30 volumio ifplugd(eth0)[941]: client: command failed: No such device (-19) Mar 01 09:56:30 volumio dhcpcd[2036]: sending commands to master dhcpcd process Mar 01 09:56:30 volumio dhcpcd[845]: control command: /sbin/dhcpcd eth0 Mar 01 09:56:30 volumio ifplugd(eth0)[941]: client: sending commands to master dhcpcd process Mar 01 09:56:31 volumio ifplugd(eth0)[941]: Program executed successfully. Mar 01 09:56:32 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:32 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:32 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:35 volumio dhcpcd[845]: eth0: probing for an IPv4LL address Mar 01 09:56:35 volumio dhcpcd[845]: eth0: DHCP lease expired Mar 01 09:56:35 volumio dhcpcd[845]: eth0: soliciting a DHCP lease Mar 01 09:56:37 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:37 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:37 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:40 volumio dhcpcd[845]: eth0: using IPv4LL address 169.254.22.202 Mar 01 09:56:40 volumio dhcpcd[845]: eth0: adding route to 169.254.0.0/16 Mar 01 09:56:40 volumio dhcpcd[845]: eth0: adding default route Mar 01 09:56:40 volumio avahi-daemon[759]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.22.202. Mar 01 09:56:40 volumio avahi-daemon[759]: New relevant interface eth0.IPv4 for mDNS. Mar 01 09:56:40 volumio avahi-daemon[759]: Registering new address record for 169.254.22.202 on eth0.IPv4. Mar 01 09:56:40 volumio kernel: ------------[ cut here ]------------ Mar 01 09:56:40 volumio kernel: NETDEV WATCHDOG: eth0 (r8169): transmit queue 0 timed out 9488 ms Mar 01 09:56:40 volumio kernel: WARNING: CPU: 2 PID: 0 at net/sched/sch_generic.c:525 dev_watchdog+0x238/0x240 Mar 01 09:56:40 volumio kernel: Modules linked in: bnep bluetooth sha3_generic jitterentropy_rng sha512_generic hmac drbg ansi_cprng ecdh_generic ecc nft_chain_nat xt_REDIRECT nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 nft_compat nf_tables libcrc32c nfnetlink 8021q garp stp mrp llc cfg80211 snd_usb_audio snd_usbmidi_lib snd_hwdep snd_seq_midi snd_seq_midi_event snd_rawmidi intel_rapl_common snd_pcm x86_pkg_temp_thermal intel_powerclamp snd_seq coretemp snd_seq_device snd_timer crc32_pclmul snd aesni_intel libaes soundcore crypto_simd efi_pstore cryptd mei_me mei sunrpc efivarfs ip_tables autofs4 loop ext4 crc32c_generic crc16 mbcache jbd2 sata_vsc sata_via sata_sx4 sata_svw sata_sis sata_sil sata_sil24 sata_qstor sata_promise sata_nv sata_mv pdc_adma pata_via pata_triflex pata_sil680 pata_serverworks pata_sch pata_rz1000 pata_rdc pata_piccolo pata_pdc202xx_old pata_pdc2027x pata_opti pata_oldpiix pata_ns87415 pata_ns87410 pata_ninja32 pata_netcell pata_mpiix pata_marvell pata_jmicron pata_it821x pata_it8213 pata_hpt37x pata_hpt366 Mar 01 09:56:40 volumio kernel: pata_efar pata_cmd64x pata_atp867x pata_atiixp pata_artop pata_amd pata_ali ahci acard_ahci libahci radeon drm_suballoc_helper nouveau mxm_wmi drm_exec gpu_sched drm_ttm_helper uas usb_storage fuse vfat fat nls_utf8 nls_cp437 hid_cherry sdhci_acpi mmc_block squashfs overlay i915 drm_buddy i2c_algo_bit drm_display_helper hid_generic r8169 ttm realtek nvme usbhid cec rc_core mdio_devres drm_kms_helper hid crc32c_intel sdhci_pci drm i2c_i801 cqhci i2c_smbus nvme_core sdhci intel_lpss_pci intel_lpss idma64 virt_dma libphy video wmi backlight pinctrl_geminilake Mar 01 09:56:40 volumio kernel: CPU: 2 PID: 0 Comm: swapper/2 Not tainted 6.6.32-volumio #2 Mar 01 09:56:40 volumio kernel: Hardware name: HARDKERNEL ODROID-H2/ODROID-H2, BIOS 1.22 11/13/2020 Mar 01 09:56:40 volumio kernel: RIP: 0010:dev_watchdog+0x238/0x240 Mar 01 09:56:40 volumio kernel: Code: ff ff 48 89 df c6 05 31 a6 bc 00 01 e8 e1 6e fa ff 44 8b 45 cc 44 89 f9 48 89 de 48 89 c2 48 c7 c7 d0 3b 1e 82 e8 f8 d3 6c ff <0f> 0b e9 27 ff ff ff 90 90 90 90 90 90 90 90 90 90 90 90 90 90 90 Mar 01 09:56:40 volumio kernel: RSP: 0018:ffffc9000015ce50 EFLAGS: 00010286 Mar 01 09:56:40 volumio kernel: RAX: 0000000000000000 RBX: ffff88810f554000 RCX: 0000000000000027 Mar 01 09:56:40 volumio kernel: RDX: ffff888277d1c5c8 RSI: 0000000000000001 RDI: ffff888277d1c5c0 Mar 01 09:56:40 volumio kernel: RBP: ffffc9000015ce88 R08: 00000000ffffefff R09: ffffc9000015ccb0 Mar 01 09:56:40 volumio kernel: R10: 0000000000000001 R11: ffffffff82450a80 R12: ffff88810f554488 Mar 01 09:56:40 volumio kernel: R13: ffff88810f5543dc R14: ffff88810025c800 R15: 0000000000000000 Mar 01 09:56:40 volumio kernel: FS: 0000000000000000(0000) GS:ffff888277d00000(0000) knlGS:0000000000000000 Mar 01 09:56:40 volumio kernel: CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 Mar 01 09:56:40 volumio kernel: CR2: 000055c87f891128 CR3: 000000000342c000 CR4: 0000000000350ee0 Mar 01 09:56:40 volumio kernel: Call Trace: Mar 01 09:56:40 volumio kernel: Mar 01 09:56:40 volumio kernel: ? show_regs+0x69/0x80 Mar 01 09:56:40 volumio kernel: ? __warn+0x8d/0x150 Mar 01 09:56:40 volumio kernel: ? dev_watchdog+0x238/0x240 Mar 01 09:56:40 volumio kernel: ? report_bug+0x171/0x1a0 Mar 01 09:56:40 volumio kernel: ? handle_bug+0x42/0x80 Mar 01 09:56:40 volumio kernel: ? exc_invalid_op+0x1c/0x70 Mar 01 09:56:40 volumio kernel: ? asm_exc_invalid_op+0x1f/0x30 Mar 01 09:56:40 volumio kernel: ? dev_watchdog+0x238/0x240 Mar 01 09:56:40 volumio kernel: ? __pfx_dev_watchdog+0x10/0x10 Mar 01 09:56:40 volumio kernel: call_timer_fn+0x29/0x130 Mar 01 09:56:40 volumio kernel: ? __pfx_dev_watchdog+0x10/0x10 Mar 01 09:56:40 volumio kernel: __run_timers+0x23f/0x2f0 Mar 01 09:56:40 volumio kernel: run_timer_softirq+0x21/0x40 Mar 01 09:56:40 volumio kernel: __do_softirq+0xcf/0x2ba Mar 01 09:56:40 volumio kernel: irq_exit_rcu+0x6f/0x90 Mar 01 09:56:40 volumio kernel: sysvec_apic_timer_interrupt+0x80/0xa0 Mar 01 09:56:40 volumio kernel: Mar 01 09:56:40 volumio kernel: Mar 01 09:56:40 volumio kernel: asm_sysvec_apic_timer_interrupt+0x1f/0x30 Mar 01 09:56:40 volumio kernel: RIP: 0010:cpuidle_enter_state+0xcc/0x440 Mar 01 09:56:40 volumio kernel: Code: e8 39 f7 57 ff e8 64 f7 ff ff 8b 53 04 49 89 c6 0f 1f 44 00 00 31 ff e8 92 2a 57 ff 80 7d d7 00 0f 85 5a 02 00 00 fb 45 85 ff <0f> 88 8d 01 00 00 49 63 f7 4c 89 f2 48 8d 04 76 48 8d 04 86 49 8d Mar 01 09:56:40 volumio kernel: RSP: 0018:ffffc900000e3e58 EFLAGS: 00000202 Mar 01 09:56:40 volumio kernel: RAX: ffff888277d2d840 RBX: ffff888277d35790 RCX: 000000000000001f Mar 01 09:56:40 volumio kernel: RDX: 0000000000000002 RSI: 0000000047af4125 RDI: 0000000000000000 Mar 01 09:56:40 volumio kernel: RBP: ffffc900000e3e90 R08: 0000000000000000 R09: 0000000000000f26 Mar 01 09:56:40 volumio kernel: R10: 0000000000000018 R11: ffff888277d2c6c4 R12: 0000000000000004 Mar 01 09:56:40 volumio kernel: R13: ffffffff8252ab00 R14: 000000152cc53561 R15: 0000000000000004 Mar 01 09:56:40 volumio kernel: ? cpuidle_enter_state+0xbe/0x440 Mar 01 09:56:40 volumio kernel: cpuidle_enter+0x32/0x50 Mar 01 09:56:40 volumio kernel: call_cpuidle+0x23/0x40 Mar 01 09:56:40 volumio kernel: do_idle+0x1ce/0x230 Mar 01 09:56:40 volumio kernel: cpu_startup_entry+0x2e/0x30 Mar 01 09:56:40 volumio kernel: start_secondary+0x118/0x140 Mar 01 09:56:40 volumio kernel: secondary_startup_64_no_verify+0x18a/0x18b Mar 01 09:56:40 volumio kernel: Mar 01 09:56:40 volumio kernel: ---[ end trace 0000000000000000 ]--- Mar 01 09:56:40 volumio kernel: r8169 0000:02:00.0 eth0: ASPM disabled on Tx timeout Mar 01 09:56:41 volumio volumio[1004]: info: Volumio Network Manager: Network status updated: 1 Mar 01 09:56:41 volumio ntpd[940]: Listen normally on 3 eth0 169.254.22.202:123 Mar 01 09:56:41 volumio ntpd[940]: new interface(s) found: waking up resolver Mar 01 09:56:42 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:56:42 volumio nmbd[912]: [2025/03/01 09:56:42.892601, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 01 09:56:42 volumio nmbd[912]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 01 09:56:42 volumio systemd[1]: Started Samba NMB Daemon. Mar 01 09:56:42 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 01 09:56:43 volumio winbindd[2126]: [2025/03/01 09:56:43.045133, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 01 09:56:43 volumio winbindd[2126]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 01 09:56:43 volumio winbindd[2126]: [2025/03/01 09:56:43.047605, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 01 09:56:43 volumio systemd[1]: Started Samba Winbind Daemon. Mar 01 09:56:43 volumio winbindd[2126]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 01 09:56:43 volumio systemd[1]: Starting Samba SMB Daemon... Mar 01 09:56:43 volumio smbd[2134]: [2025/03/01 09:56:43.258092, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 01 09:56:43 volumio smbd[2134]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 01 09:56:43 volumio systemd[1]: Started Samba SMB Daemon. Mar 01 09:56:50 volumio dhcpcd[845]: eth0: offered 192.168.1.22 from 192.168.1.1 `ecosystem.home.cisco.com' Mar 01 09:56:51 volumio dhcpcd[845]: eth0: probing address 192.168.1.22/24 Mar 01 09:56:56 volumio dhcpcd[845]: eth0: leased 192.168.1.22 for 86400 seconds Mar 01 09:56:56 volumio dhcpcd[845]: eth0: adding route to 192.168.1.0/24 Mar 01 09:56:56 volumio dhcpcd[845]: eth0: changing default route via 192.168.1.1 Mar 01 09:56:56 volumio avahi-daemon[759]: Registering new address record for 192.168.1.22 on eth0.IPv4. Mar 01 09:56:56 volumio ntpd[940]: ntpd exiting on signal 15 (Terminated) Mar 01 09:56:56 volumio systemd[1]: Stopping Network Time Service... Mar 01 09:56:56 volumio systemd[1]: ntp.service: Succeeded. Mar 01 09:56:56 volumio systemd[1]: Stopped Network Time Service. Mar 01 09:56:56 volumio systemd[1]: Starting Network Time Service... Mar 01 09:56:56 volumio ntpd[2204]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 01 09:56:56 volumio ntpd[2204]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 01 09:56:56 volumio systemd[1]: Started Network Time Service. Mar 01 09:56:56 volumio avahi-daemon[759]: Withdrawing address record for 169.254.22.202 on eth0. Mar 01 09:56:56 volumio avahi-daemon[759]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.22.202. Mar 01 09:56:56 volumio avahi-daemon[759]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.22. Mar 01 09:56:56 volumio ntpd[2210]: proto: precision = 0.073 usec (-24) Mar 01 09:56:56 volumio dhcpcd[845]: eth0: deleting route to 169.254.0.0/16 Mar 01 09:56:56 volumio ntpd[2210]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 01 09:56:56 volumio ntpd[2210]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 01 09:56:56 volumio ntpd[2210]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 795 days ago Mar 01 09:56:56 volumio ntpd[2210]: Listen and drop on 0 v6wildcard [::]:123 Mar 01 09:56:56 volumio ntpd[2210]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 01 09:56:56 volumio ntpd[2210]: Listen normally on 2 lo 127.0.0.1:123 Mar 01 09:56:56 volumio ntpd[2210]: Listen normally on 3 eth0 192.168.1.22:123 Mar 01 09:56:56 volumio ntpd[2210]: Listening on routing socket on fd #20 for interface updates Mar 01 09:56:56 volumio ntpd[2210]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 01 09:56:56 volumio ntpd[2210]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 01 09:56:57 volumio ntpd[2210]: Soliciting pool server 175.195.167.194 Mar 01 09:56:57 volumio volumio-time-update[741]: volumio-time-update-util: Date not found in response Mar 01 09:56:57 volumio volumio-time-update[741]: volumio-time-update-util: Retrying in 5 seconds... Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 01 09:56:57 volumio volumio[1004]: info: Adding plugin bluetooth to MyMusic Plugins Mar 01 09:56:57 volumio volumio[1004]: info: Adding plugin multiroom to MyMusic Plugins Mar 01 09:56:57 volumio volumio[1004]: info: Adding plugin metavolumio to MyMusic Plugins Mar 01 09:56:57 volumio volumio[1004]: info: Adding plugin cd_controller to MyMusic Plugins Mar 01 09:56:57 volumio volumio[1004]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 01 09:56:57 volumio volumio[1004]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 01 09:56:57 volumio volumio[1004]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 01 09:56:58 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 09:56:58 volumio volumio[1004]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 09:56:58 volumio volumio[1004]: info: Starting MyVolumio Remote Streaming Endpoints Mar 01 09:56:58 volumio volumio[1004]: info: MyVolumio login type: Token Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 01 09:56:58 volumio ntpd[2210]: Soliciting pool server 121.174.142.82 Mar 01 09:56:58 volumio ntpd[2210]: Soliciting pool server 121.174.142.81 Mar 01 09:56:58 volumio volumio[1004]: info: Starting Streaming Service Transparent Proxy Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 01 09:56:58 volumio volumio[1004]: info: Streaming services startup Mar 01 09:56:58 volumio volumio[1004]: info: Starting Streaming Daemon Mar 01 09:56:58 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 01 09:56:58 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 09:56:58 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Mar 01 09:56:58 volumio volumio[1004]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 01 09:56:58 volumio volumio[1004]: error: Cannot start Volumio Streaming Daemon Mar 01 09:56:58 volumio volumio[1004]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 01 09:56:58 volumio volumio[1004]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 01 09:56:58 volumio volumio[1004]: STREAMING PROXY: Starting server on port 3245 Mar 01 09:56:58 volumio volumio[1004]: Node JS runtime: 14 Mar 01 09:56:59 volumio volumio[1004]: error: MyVolumio Custom Token format not valid, refreshing it Mar 01 09:56:59 volumio ntpd[2210]: Soliciting pool server 193.123.243.2 Mar 01 09:57:00 volumio ntpd[2210]: Soliciting pool server 221.151.118.78 Mar 01 09:57:00 volumio ntpd[2210]: Soliciting pool server 141.164.43.237 Mar 01 09:57:00 volumio volumio[1004]: info: Discovery: adding 23f9540f-f1d9-4979-8fe6-35b2e7f154d3 Mar 01 09:57:00 volumio volumio[1004]: info: Discovery: Found device Volumio Mar 01 09:57:00 volumio volumio[1004]: info: CoreCommandRouter::volumioGetState Mar 01 09:57:00 volumio volumio[1004]: info: CorePlayQueue::getTrack 0 Mar 01 09:57:01 volumio volumio[1004]: info: MyVolumio login type: Token Mar 01 09:57:01 volumio ntpd[2210]: Soliciting pool server 194.0.5.123 Mar 01 09:57:01 volumio ntpd[2210]: Soliciting pool server 2603:c022:8004:7800:65bf:c600:a739:1bee Mar 01 09:57:01 volumio volumio[1004]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 01 09:57:02 volumio volumio-time-update[741]: volumio-time-update-util: Fetching time from Volumio... Mar 01 09:57:03 volumio volumio[1004]: info: MyVolumio token set successfully Mar 01 09:57:03 volumio volumio[1004]: info: MYVOLUMIO: Adding device Mar 01 09:57:03 volumio volumio[1004]: info: MYVOLUMIO: Evaluating Server Mar 01 09:57:03 volumio volumio[1004]: info: MyVolumio status changed Mar 01 09:57:03 volumio volumio[1004]: info: Streaming services startup Mar 01 09:57:03 volumio volumio[1004]: info: Starting Streaming Daemon Mar 01 09:57:03 volumio volumio[1004]: info: Removing browser output: myVolumio user plan is not superstar Mar 01 09:57:03 volumio volumio[1004]: info: Removing audio output: Mar 01 09:57:03 volumio volumio[1004]: info: Stoppping Tunnel 1 Mar 01 09:57:03 volumio sudo[2292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 01 09:57:03 volumio sudo[2292]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 09:57:03 volumio sudo[2292]: pam_unix(sudo:session): session closed for user root Mar 01 09:57:03 volumio sudo[2295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 01 09:57:03 volumio volumio[1004]: error: Cannot start Volumio Streaming Daemon Mar 01 09:57:03 volumio sudo[2295]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 09:57:03 volumio volumio[1004]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 01 09:57:03 volumio volumio[1004]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 01 09:57:03 volumio sudo[2295]: pam_unix(sudo:session): session closed for user root Mar 01 09:57:03 volumio volumio[1004]: info: Remote SSH Stopped Mar 01 09:57:03 volumio volumio-time-update[741]: volumio-time-update-util: Setting system time to: 2025-03-01 09:57:03 Mar 01 09:57:03 volumio sudo[2298]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-01 09:57:03 Mar 01 09:57:03 volumio sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 09:57:03 volumio dbus-daemon[761]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.16' (uid=0 pid=2299 comm="timedatectl set-time 2025-03-01 09:57:03 ") Mar 01 09:57:03 volumio systemd[1]: Starting Time & Date Service... Mar 01 09:57:03 volumio dbus-daemon[761]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 01 09:57:03 volumio systemd[1]: Started Time & Date Service. Mar 01 09:57:03 volumio systemd-timedated[2300]: Changed local time to Sat Mar 1 09:57:03 2025 Mar 01 09:57:03 volumio sudo[2298]: pam_unix(sudo:session): session closed for user root Mar 01 09:57:03 volumio volumio-time-update[741]: volumio-time-update-util: System time updated successfully. Mar 01 09:57:03 volumio systemd[1]: Started Volumio Time Update Utility. Mar 01 09:57:03 volumio systemd[1]: Reached target Multi-User System. Mar 01 09:57:03 volumio systemd[1]: Reached target Graphical Interface. Mar 01 09:57:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 01 09:57:03 volumio volumio[1004]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Mar 01 09:57:03 volumio volumio[1004]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 09:57:03 volumio volumio[1004]: Error: Unable to resolve or reject the same promise twice Mar 01 09:57:03 volumio volumio[1004]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 01 09:57:03 volumio volumio[1004]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Mar 01 09:57:03 volumio volumio[1004]: at Socket.emit (events.js:412:35) Mar 01 09:57:03 volumio volumio[1004]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 01 09:57:03 volumio volumio[1004]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 01 09:57:03 volumio volumio[1004]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 09:57:03 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 01 09:57:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 01 09:57:03 volumio systemd[1]: Startup finished in 3.981s (firmware) + 952ms (loader) + 6.022s (kernel) + 1min 48.574s (userspace) = 1min 59.530s. Mar 01 09:57:03 volumio sudo[2312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 09:56 Mar 01 09:57:03 volumio sudo[2312]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"