-- 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"