-- Logs begin at Sat 2024-07-13 13:17:02 UTC, end at Sun 2024-07-21 06:52:05 UTC. -- Jul 21 06:51:19 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 06:51:19 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 06:51:19 volumio volumio[891]: info: Discovery: Getting this device information Jul 21 06:51:19 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Jul 21 06:51:19 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 06:51:24 volumio volumio[891]: info: CoreCommandRouter::volumioPause Jul 21 06:51:24 volumio volumio[891]: info: CoreStateMachine::pause Jul 21 06:51:24 volumio volumio[891]: info: CoreStateMachine::stPlaybackTimer Jul 21 06:51:24 volumio volumio[891]: info: CoreStateMachine::servicePause Jul 21 06:51:24 volumio volumio[891]: info: CoreCommandRouter::servicePause Jul 21 06:51:24 volumio volumio[891]: info: Spotify Received pause Jul 21 06:51:25 volumio volumio[15527]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:232:61 Jul 21 06:51:25 volumio volumio[15527]: stack backtrace: Jul 21 06:51:25 volumio volumio[15527]: 0: 0x9952fc - std::backtrace_rs::backtrace::libunwind::trace::h9e46ee8b06338e2c Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Jul 21 06:51:25 volumio volumio[15527]: 1: 0x9952fc - std::backtrace_rs::backtrace::trace_unsynchronized::h0c41dfeb26b6a335 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Jul 21 06:51:25 volumio volumio[15527]: 2: 0x9952fc - std::sys_common::backtrace::_print_fmt::hb5a214bdbaf6bbdb Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Jul 21 06:51:25 volumio volumio[15527]: 3: 0x9952fc - ::fmt::hbc32816bedd649a5 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Jul 21 06:51:25 volumio volumio[15527]: 4: 0x9bf454 - core::fmt::write::hc9f0f3e5f91c6543 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Jul 21 06:51:25 volumio volumio[15527]: 5: 0x98d138 - std::io::Write::write_fmt::he4d0bfc419f79f1b Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Jul 21 06:51:25 volumio volumio[15527]: 6: 0x9980a0 - std::sys_common::backtrace::_print::ha58d83ed5fb7e146 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Jul 21 06:51:25 volumio volumio[15527]: 7: 0x9980a0 - std::sys_common::backtrace::print::ha811f04da3810355 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Jul 21 06:51:25 volumio volumio[15527]: 8: 0x9980a0 - std::panicking::default_hook::{{closure}}::h3b84d19a58ce7d1a Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Jul 21 06:51:25 volumio volumio[15527]: 9: 0x997c74 - std::panicking::default_hook::h8c6554c515925183 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Jul 21 06:51:25 volumio volumio[15527]: 10: 0x998880 - std::panicking::rust_panic_with_hook::h9acdb8c0e7ce2409 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Jul 21 06:51:25 volumio volumio[15527]: 11: 0x9984b0 - std::panicking::begin_panic_handler::{{closure}}::hb553c3332fade7ef Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Jul 21 06:51:25 volumio volumio[15527]: 12: 0x99587c - std::sys_common::backtrace::__rust_end_short_backtrace::h20986b44a69d8c2e Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Jul 21 06:51:25 volumio volumio[15527]: 13: 0x998244 - rust_begin_unwind Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Jul 21 06:51:25 volumio volumio[15527]: 14: 0x465e1c - core::panicking::panic_fmt::h7ec84e37a08e9c06 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Jul 21 06:51:25 volumio volumio[15527]: 15: 0x465ef0 - core::result::unwrap_failed::he6b1e68d2754f9ee Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/result.rs:1749:5 Jul 21 06:51:25 volumio volumio[15527]: 16: 0x585888 - librespot_playback::player::Player::command::h6b48b37650069fdf Jul 21 06:51:25 volumio volumio[15527]: 17: 0x5859e8 - librespot_playback::player::Player::pause::h3e789da42e3c4539 Jul 21 06:51:25 volumio volumio[15527]: 18: 0x557ac8 - librespot_connect::spirc::SpircTask::handle_pause::hc6fa9d4ee838ea00 Jul 21 06:51:25 volumio volumio[15527]: 19: 0x556a3c - ::poll::h7a3420f92eca1f08 Jul 21 06:51:25 volumio volumio[15527]: 20: 0x48da2c - ::poll::h56f06c6a85fa50ff Jul 21 06:51:25 volumio volumio[15527]: 21: 0x4ac068 - futures::task_impl::std::set::hf891a4346b25adb2 Jul 21 06:51:25 volumio volumio[15527]: 22: 0x4c3738 - std::thread::local::LocalKey::with::hf342fd7bf0a9ad1d Jul 21 06:51:25 volumio volumio[15527]: 23: 0x4a7ebc - tokio_current_thread::Entered

::block_on::h5dae150d7e784e05 Jul 21 06:51:25 volumio volumio[15527]: 24: 0x4c3144 - std::thread::local::LocalKey::with::h502c80dedd187c37 Jul 21 06:51:25 volumio volumio[15527]: 25: 0x4aaf10 - tokio::runtime::current_thread::runtime::Runtime::block_on::ha513af9e8ad283e3 Jul 21 06:51:25 volumio volumio[15527]: 26: 0x4905e4 - vollibrespot::main::h1332d636f2a2ce21 Jul 21 06:51:25 volumio volumio[15527]: 27: 0x4b0710 - std::sys_common::backtrace::__rust_begin_short_backtrace::h82356919246fa2c0 Jul 21 06:51:25 volumio volumio[15527]: 28: 0x4d7d3c - std::rt::lang_start::{{closure}}::h572c7a9ced59a0e0 Jul 21 06:51:25 volumio volumio[15527]: 29: 0x994994 - core::ops::function::impls:: for &F>::call_once::hcde138729d892cf0 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Jul 21 06:51:25 volumio volumio[891]: info: [1721544685075] ControllerSpotify::pushState Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::servicePushState Jul 21 06:51:25 volumio volumio[891]: info: CoreStateMachine::pushState Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Jul 21 06:51:25 volumio volumio[15527]: 30: 0x994994 - std::panicking::try::do_call::hc416f030da364971 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Jul 21 06:51:25 volumio volumio[15527]: 31: 0x994994 - std::panicking::try::h782c84118f0fa3bc Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Jul 21 06:51:25 volumio volumio[15527]: 32: 0x994994 - std::panic::catch_unwind::he6fcd9d27a61e87e Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Jul 21 06:51:25 volumio volumio[15527]: 33: 0x994994 - std::rt::lang_start_internal::{{closure}}::h1053f42356d31350 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Jul 21 06:51:25 volumio volumio[15527]: 34: 0x994994 - std::panicking::try::do_call::h32b2456159c60242 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Jul 21 06:51:25 volumio volumio[15527]: 35: 0x994994 - std::panicking::try::h3d7616087024e730 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Jul 21 06:51:25 volumio volumio[15527]: 36: 0x994994 - std::panic::catch_unwind::he2fb360b733279d6 Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Jul 21 06:51:25 volumio volumio[15527]: 37: 0x994994 - std::rt::lang_start_internal::hd11cc100ac1194bb Jul 21 06:51:25 volumio volumio[15527]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Jul 21 06:51:25 volumio volumio[15527]: 38: 0x4926fc - main Jul 21 06:51:25 volumio volumio[15527]: 39: 0xb6c0c768 - __libc_start_main Jul 21 06:51:25 volumio volumio[15527]: [Vollibrespot] : EventSender disconnected Jul 21 06:51:25 volumio volumio[15527]: [Vollibrespot] : Player thread panicked! Jul 21 06:51:25 volumio volumio[891]: [SpotifyConnect] Device palyback is inactive Jul 21 06:51:25 volumio volumio[891]: [SpotifyConnect] Sink released Jul 21 06:51:25 volumio volumio[891]: info: [1721544685323] ControllerSpotify::pushState Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::servicePushState Jul 21 06:51:25 volumio volumio[891]: info: CoreStateMachine::pushState Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Jul 21 06:51:25 volumio volumio[891]: info: CorePlayQueue::getTrack 1 Jul 21 06:51:25 volumio volumio[891]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Kicks","artist":"Caterina Caselli","album":"Casco D'oro","albumart":"https://i.scdn.co/image/ab67616d0000b2735fc53afb81213d573fd85f2e","uri":"spotify:track:7k22ksDU7peuiK6xLWmouZ","trackType":"spotify","seek":0,"duration":151,"samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","channels":2,"stream":false,"repeatMode":"all"} Jul 21 06:51:25 volumio volumio[891]: verbose: CURRENT POSITION 1 Jul 21 06:51:25 volumio volumio[891]: info: CoreStateMachine::syncState stateService stop Jul 21 06:51:25 volumio volumio[891]: info: CoreStateMachine::syncState currentStatus pause Jul 21 06:51:25 volumio volumio[891]: info: CoreStateMachine::pushState Jul 21 06:51:25 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Jul 21 06:51:25 volumio systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Jul 21 06:51:25 volumio systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Jul 21 06:51:27 volumio systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Jul 21 06:51:27 volumio systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Jul 21 06:51:27 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jul 21 06:51:27 volumio systemd[1]: Started Volspotconnect2 Daemon. Jul 21 06:51:28 volumio volumio[24723]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jul 21 06:51:28 volumio volumio[24723]: Reading Config from "/tmp/volspotify.toml" Jul 21 06:51:28 volumio volumio[24723]: [Vollibrespot] : Using Alsa backend with device: volumio Jul 21 06:51:28 volumio volumio[24723]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Jul 21 06:51:29 volumio volumio[24723]: [Vollibrespot] : Authenticated as "31waql2gw4ltfg7ggpwaor5hv4ri" ! Jul 21 06:51:29 volumio volumio[24723]: [Vollibrespot] : Using alsa sink Jul 21 06:51:29 volumio volumio[24723]: [Vollibrespot] : Country: "IT" Jul 21 06:51:29 volumio volumio[24723]: [Vollibrespot] : Metadata pipe established Jul 21 06:51:29 volumio volumio[24723]: [Vollibrespot] : Event: Volume { volume_to_mixer: 36045 } Jul 21 06:51:29 volumio volumio[891]: [SpotifyConnect] Volume Spotify: 55.001144426642256 Volumio: 55 Jul 21 06:51:29 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 21 06:51:29 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 21 06:51:29 volumio volumio[891]: info: Discovery: Getting this device information Jul 21 06:51:29 volumio volumio[891]: info: CoreCommandRouter::volumioGetState Jul 21 06:51:30 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 21 06:51:33 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 21 06:51:35 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 21 06:51:35 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 21 06:51:36 volumio volumio[891]: error: Cannot download Available plugins list: Error: got 404 response Jul 21 06:51:38 volumio volumio[891]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jul 21 06:51:38 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 06:51:38 volumio volumio[891]: [SpotifyConnect] Closing metadata listener Jul 21 06:51:38 volumio volumio[891]: info: CoreStateMachine::resetVolumioState Jul 21 06:51:38 volumio volumio[891]: info: CoreStateMachine::getcurrentVolume Jul 21 06:51:38 volumio volumio[891]: info: CoreCommandRouter::volumioRetrievevolume Jul 21 06:51:38 volumio volumio[891]: info: Disabling plugin spop Jul 21 06:51:38 volumio volumio[891]: info: Done. Jul 21 06:51:38 volumio sudo[24733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Jul 21 06:51:38 volumio volumio[891]: Unable to parse: Jul 21 06:51:38 volumio volumio[891]: Simple mixer control 'Master',0 Jul 21 06:51:38 volumio volumio[891]: Capabilities: volume volume-joined Jul 21 06:51:38 volumio volumio[891]: Playback channels: Mono Jul 21 06:51:38 volumio volumio[891]: Capture channels: Mono Jul 21 06:51:38 volumio volumio[891]: Limits: 0 - 248 Jul 21 06:51:38 volumio volumio[891]: Mono: 136 [55%] Jul 21 06:51:38 volumio volumio[891]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 21 06:51:38 volumio sudo[24733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:51:38 volumio volumio[891]: Unable to parse: Jul 21 06:51:38 volumio volumio[891]: Simple mixer control 'Master',0 Jul 21 06:51:38 volumio volumio[891]: Capabilities: volume volume-joined Jul 21 06:51:38 volumio volumio[891]: Playback channels: Mono Jul 21 06:51:38 volumio volumio[891]: Capture channels: Mono Jul 21 06:51:38 volumio volumio[891]: Limits: 0 - 248 Jul 21 06:51:38 volumio volumio[891]: Mono: 136 [55%] Jul 21 06:51:38 volumio volumio[891]: info: VolumeController:: Volume=undefined Mute =false Jul 21 06:51:38 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Jul 21 06:51:38 volumio systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Jul 21 06:51:38 volumio systemd[1]: volspotconnect.service: Succeeded. Jul 21 06:51:38 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Jul 21 06:51:38 volumio volumio[891]: info: CoreStateMachine::pushState Jul 21 06:51:39 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 21 06:51:39 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Jul 21 06:51:39 volumio volumio[891]: info: CoreStateMachine::updateTrackBlock Jul 21 06:51:39 volumio volumio[891]: info: CorePlayQueue::getTrackBlock Jul 21 06:51:39 volumio volumio[891]: info: CoreCommandRouter::volumioRetrievevolume Jul 21 06:51:39 volumio sudo[24733]: pam_unix(sudo:session): session closed for user root Jul 21 06:51:39 volumio volumio[891]: [SpotifyConnect] Vollibrespot Daemon service stoped! Jul 21 06:51:39 volumio volumio[891]: Unable to parse: Jul 21 06:51:39 volumio volumio[891]: Simple mixer control 'Master',0 Jul 21 06:51:39 volumio volumio[891]: Capabilities: volume volume-joined Jul 21 06:51:39 volumio volumio[891]: Playback channels: Mono Jul 21 06:51:39 volumio volumio[891]: Capture channels: Mono Jul 21 06:51:39 volumio volumio[891]: Limits: 0 - 248 Jul 21 06:51:39 volumio volumio[891]: Mono: 136 [55%] Jul 21 06:51:39 volumio volumio[891]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 21 06:51:39 volumio volumio[891]: Unable to parse: Jul 21 06:51:39 volumio volumio[891]: Simple mixer control 'Master',0 Jul 21 06:51:39 volumio volumio[891]: Capabilities: volume volume-joined Jul 21 06:51:39 volumio volumio[891]: Playback channels: Mono Jul 21 06:51:39 volumio volumio[891]: Capture channels: Mono Jul 21 06:51:39 volumio volumio[891]: Limits: 0 - 248 Jul 21 06:51:39 volumio volumio[891]: Mono: 136 [55%] Jul 21 06:51:39 volumio volumio[891]: info: VolumeController:: Volume=undefined Mute =false Jul 21 06:51:39 volumio volumio[891]: info: CoreStateMachine::pushState Jul 21 06:51:39 volumio volumio[891]: info: CoreCommandRouter::volumioPushState Jul 21 06:51:42 volumio volumio[891]: info: Enabling plugin spop Jul 21 06:51:42 volumio volumio[891]: info: Loading plugin "spop"... Jul 21 06:51:43 volumio volumio[891]: info: PLUGIN START: spop Jul 21 06:51:43 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 21 06:51:43 volumio volumio[891]: [SpotifyConnect] Creating VLS config file Jul 21 06:51:43 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 06:51:43 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 06:51:43 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 06:51:43 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 06:51:43 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 21 06:51:43 volumio volumio[891]: [SpotifyConnect] Starting metadata listener Jul 21 06:51:43 volumio sudo[24741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jul 21 06:51:43 volumio sudo[24741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:51:43 volumio sudo[24741]: pam_unix(sudo:session): session closed for user root Jul 21 06:51:43 volumio sudo[24744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jul 21 06:51:43 volumio sudo[24744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 21 06:51:44 volumio systemd[1]: Started Volspotconnect2 Daemon. Jul 21 06:51:44 volumio sudo[24744]: pam_unix(sudo:session): session closed for user root Jul 21 06:51:44 volumio volumio[24746]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Jul 21 06:51:44 volumio volumio[24746]: Reading Config from "/tmp/volspotify.toml" Jul 21 06:51:44 volumio volumio[24746]: [Vollibrespot] : Using Alsa backend with device: volumio Jul 21 06:51:44 volumio volumio[24746]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Jul 21 06:51:44 volumio volumio[891]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Jul 21 06:51:44 volumio volumio[891]: [SpotifyConnect] Vollibrespot Daemon service started! Jul 21 06:51:44 volumio volumio[891]: [Metrics] SpotifyConnect: 1s 362.71ms Jul 21 06:51:44 volumio volumio[891]: info: Done. Jul 21 06:51:45 volumio volumio[24746]: [Vollibrespot] : Authenticated as "31waql2gw4ltfg7ggpwaor5hv4ri" ! Jul 21 06:51:45 volumio volumio[24746]: [Vollibrespot] : Using alsa sink Jul 21 06:51:45 volumio volumio[24746]: [Vollibrespot] : Country: "IT" Jul 21 06:51:45 volumio volumio[24746]: [Vollibrespot] : Metadata pipe established Jul 21 06:51:45 volumio volumio[24746]: [Vollibrespot] : Event: Volume { volume_to_mixer: 36045 } Jul 21 06:51:45 volumio volumio[891]: [SpotifyConnect] Volume Spotify: 55.001144426642256 Volumio: 55 Jul 21 06:51:48 volumio volumio[891]: info: CoreCommandRouter::volumioVolatilePlay Jul 21 06:51:48 volumio volumio[891]: info: CoreStateMachine::volatilePlay Jul 21 06:51:48 volumio volumio[891]: info: WARNING: No play method for volatile plugin undefined Jul 21 06:51:48 volumio volumio[891]: info: Checking Spotify Web API Jul 21 06:51:48 volumio volumio[24746]: [Vollibrespot] : ReqToken Jul 21 06:51:48 volumio volumio[891]: info: New Spotify Access Token Received Jul 21 06:51:48 volumio volumio[891]: info: Initializing Spotify Web API Jul 21 06:51:49 volumio volumio[891]: info: Initliazing Spotify Browsing Facility Jul 21 06:51:49 volumio volumio[891]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 21 06:51:49 volumio volumio[891]: info: [1721544709044] CoreMusicLibrary::Adding element Spotify Jul 21 06:51:49 volumio volumio[891]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 21 06:51:49 volumio volumio[891]: Cannot find translation for sourceSpotify Jul 21 06:51:59 volumio volumio[891]: info: CoreCommandRouter::volumioPlay Jul 21 06:51:59 volumio volumio[891]: verbose: UNSET VOLATILE Jul 21 06:51:59 volumio volumio[891]: [SpotifyConnect] unSetVolatile called Jul 21 06:51:59 volumio volumio[891]: info: Spotify Unset Volatile called Jul 21 06:51:59 volumio volumio[891]: [SpotifyConnect] Relinquishing Volumio State to another service Jul 21 06:51:59 volumio volumio[891]: [SpotifyConnect] Spotify Received stop Jul 21 06:51:59 volumio volumio[891]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 06:52:00 volumio volumio[891]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Jul 21 06:52:00 volumio volumio[891]: at healthCheck (dgram.js:897:11) Jul 21 06:52:00 volumio volumio[891]: at Socket.send (dgram.js:622:3) Jul 21 06:52:00 volumio volumio[891]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Jul 21 06:52:00 volumio volumio[891]: at ControllerSpotify.stop (/data/plugins/music_service/spop/index.js:2644:19) Jul 21 06:52:00 volumio volumio[891]: at ControllerSpotify.spotConnUnsetVolatile (/data/plugins/music_service/spop/index.js:2335:17) Jul 21 06:52:00 volumio volumio[891]: at ControllerSpotify.unsetVol (/data/plugins/music_service/spop/index.js:68:21) Jul 21 06:52:00 volumio volumio[891]: at CoreStateMachine.unSetVolatile (/volumio/app/statemachine.js:1532:27) Jul 21 06:52:00 volumio volumio[891]: at CoreCommandRouter.volumioPlay (/volumio/app/index.js:1346:21) Jul 21 06:52:00 volumio volumio[891]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:211:35) Jul 21 06:52:00 volumio volumio[891]: at Socket.emit (events.js:315:20) { Jul 21 06:52:00 volumio volumio[891]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Jul 21 06:52:00 volumio volumio[891]: } Jul 21 06:52:00 volumio volumio[891]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 21 06:52:05 volumio sudo[24764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-21 06:51 Jul 21 06:52:05 volumio sudo[24764]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"