-- Logs begin at Mon 2024-10-21 10:28:06 UTC, end at Mon 2024-10-21 11:01:49 UTC. -- Oct 21 11:00:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 11:00:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 11:00:03 volumio volumio[1203]: info: Discovery: Getting this device information Oct 21 11:00:03 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Oct 21 11:00:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 21 11:00:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:00:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:00:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 11:00:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 11:00:33 volumio volumio[1203]: info: Discovery: Getting this device information Oct 21 11:00:33 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Oct 21 11:00:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 21 11:00:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:00:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:00:46 volumio volumio[4334]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Authenticated as "11131423252" ! Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:Power LED index:0 Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(0)[dB]) -- max: 0 (MilliBel(0)[dB]) HW: false Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Switching to linear volume mapping, control range: MilliBel(0) Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Oct 21 11:00:47 volumio volumio[4334]: [Vollibrespot] : Using alsa sink Oct 21 11:00:47 volumio volumio[4334]: thread 'main' panicked at 'Couldn't get current volume: Error("snd_mixer_selem_get_playback_volume", Sys(EINVAL))', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/mixer/alsamixer.rs:94:14 Oct 21 11:00:47 volumio volumio[4334]: stack backtrace: Oct 21 11:00:47 volumio volumio[4334]: 0: 0xa7333c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Oct 21 11:00:47 volumio volumio[4334]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Oct 21 11:00:47 volumio volumio[4334]: 1: 0xa7333c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Oct 21 11:00:47 volumio volumio[4334]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Oct 21 11:00:47 volumio volumio[4334]: 2: 0xa7333c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/sys_common/backtrace.rs:78 Oct 21 11:00:47 volumio volumio[4334]: 3: 0xa7333c - ::fmt::he1a5d6f378e506c4 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/sys_common/backtrace.rs:59 Oct 21 11:00:47 volumio volumio[4334]: 4: 0xa93d5c - core::fmt::write::hb37ae5a5e0b70623 Oct 21 11:00:47 volumio volumio[4334]: at src/libcore/fmt/mod.rs:1076 Oct 21 11:00:47 volumio volumio[4334]: 5: 0xa6cc64 - std::io::Write::write_fmt::ha24bb3f5a858327b Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/io/mod.rs:1537 Oct 21 11:00:47 volumio volumio[4334]: 6: 0xa75974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/sys_common/backtrace.rs:62 Oct 21 11:00:47 volumio volumio[4334]: 7: 0xa75974 - std::sys_common::backtrace::print::h2217cbc390250439 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/sys_common/backtrace.rs:49 Oct 21 11:00:47 volumio volumio[4334]: 8: 0xa75974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panicking.rs:198 Oct 21 11:00:47 volumio volumio[4334]: 9: 0xa75640 - std::panicking::default_hook::h46ab82039cbc65eb Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panicking.rs:217 Oct 21 11:00:47 volumio volumio[4334]: 10: 0xa76054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panicking.rs:526 Oct 21 11:00:47 volumio volumio[4334]: 11: 0xa75c54 - rust_begin_unwind Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panicking.rs:437 Oct 21 11:00:47 volumio volumio[4334]: 12: 0xa91594 - core::panicking::panic_fmt::ha292e19d5ae716ed Oct 21 11:00:47 volumio volumio[4334]: at src/libcore/panicking.rs:85 Oct 21 11:00:47 volumio volumio[4334]: 13: 0xa913ec - core::option::expect_none_failed::h5afc8f3774fae01b Oct 21 11:00:47 volumio volumio[4334]: at src/libcore/option.rs:1269 Oct 21 11:00:47 volumio volumio[4334]: 14: 0x6591f8 - librespot_playback::mixer::alsamixer::AlsaMixer::map_volume::h7d5c6cd4990de647 Oct 21 11:00:47 volumio volumio[4334]: 15: 0x659c74 - ::set_volume::h1824875d048eedd6 Oct 21 11:00:47 volumio volumio[4334]: 16: 0x6217d8 - librespot_connect::spirc::SpircTask::set_volume::hbcc0ef22019a3745 Oct 21 11:00:47 volumio volumio[4334]: 17: 0x61bb64 - librespot_connect::spirc::Spirc::new::hfd339f4dcad88e87 Oct 21 11:00:47 volumio systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a Oct 21 11:00:47 volumio systemd[1]: Unit volspotconnect2.service entered failed state. Oct 21 11:00:47 volumio volumio[4334]: 18: 0x593890 - ::poll::hc5e7d2d1b7dbef13 Oct 21 11:00:47 volumio volumio[4334]: 19: 0x55b844 - futures::task_impl::std::set::h8f081cf0436110c9 Oct 21 11:00:47 volumio volumio[4334]: 20: 0x56f41c - std::thread::local::LocalKey::with::h711a13323aafc45a Oct 21 11:00:47 volumio volumio[4334]: 21: 0x54ba50 - tokio_current_thread::Entered

::block_on::h339073902a399eb7 Oct 21 11:00:47 volumio volumio[4334]: 22: 0x56fe00 - std::thread::local::LocalKey::with::hff64c13ccfec5327 Oct 21 11:00:47 volumio volumio[4334]: 23: 0x57c20c - tokio_reactor::with_default::h7ef12e65cb103d2f Oct 21 11:00:47 volumio volumio[4334]: 24: 0x54f9f8 - tokio::runtime::current_thread::runtime::Runtime::block_on::h043f136d6b8cf48f Oct 21 11:00:47 volumio volumio[4334]: 25: 0x5952c8 - vollibrespot::main::h02a0d49d4cc5eaa6 Oct 21 11:00:47 volumio volumio[4334]: 26: 0x57cf18 - std::rt::lang_start::{{closure}}::h279cd2b7929fdba7 Oct 21 11:00:47 volumio volumio[4334]: 27: 0xa76404 - std::rt::lang_start_internal::{{closure}}::he93bfc404849b78a Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/rt.rs:52 Oct 21 11:00:47 volumio volumio[4334]: 28: 0xa76404 - std::panicking::try::do_call::h6e9e98f4078affb0 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panicking.rs:348 Oct 21 11:00:47 volumio volumio[4334]: 29: 0xa76404 - std::panicking::try::h2e68d4f7f799a6df Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panicking.rs:325 Oct 21 11:00:47 volumio volumio[4334]: 30: 0xa76404 - std::panic::catch_unwind::h8880a4c07cc66391 Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/panic.rs:394 Oct 21 11:00:47 volumio volumio[4334]: 31: 0xa76404 - std::rt::lang_start_internal::hf4ae2140248bf16b Oct 21 11:00:47 volumio volumio[4334]: at src/libstd/rt.rs:51 Oct 21 11:00:47 volumio volumio[4334]: 32: 0x595d84 - main Oct 21 11:00:47 volumio volumio[4334]: 33: 0x76d1f294 - __libc_start_main Oct 21 11:00:49 volumio systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 21 11:00:49 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 21 11:00:49 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 21 11:00:49 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 21 11:00:49 volumio volumio[6301]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 21 11:00:49 volumio volumio[6301]: Reading Config from "volspotify.toml" Oct 21 11:00:49 volumio volumio[6301]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Oct 21 11:00:49 volumio volumio[6301]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 21 11:00:50 volumio kernel: Under-voltage detected! (0x00050005) Oct 21 11:00:54 volumio kernel: Voltage normalised (0x00000000) Oct 21 11:00:59 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Oct 21 11:00:59 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 21 11:00:59 volumio volumio[1203]: info: Listing playlists Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::volumioVolatilePlay Oct 21 11:01:03 volumio volumio[1203]: info: CoreStateMachine::volatilePlay Oct 21 11:01:03 volumio volumio[1203]: info: WARNING: No play method for volatile plugin spop Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 11:01:03 volumio volumio[1203]: info: Discovery: Getting this device information Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:01:03 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:01:05 volumio volumio[1203]: info: CoreCommandRouter::volumioVolatilePlay Oct 21 11:01:05 volumio volumio[1203]: info: CoreStateMachine::volatilePlay Oct 21 11:01:05 volumio volumio[1203]: info: WARNING: No play method for volatile plugin spop Oct 21 11:01:08 volumio volumio[1203]: info: CoreCommandRouter::volumioVolatilePlay Oct 21 11:01:08 volumio volumio[1203]: info: CoreStateMachine::volatilePlay Oct 21 11:01:08 volumio volumio[1203]: info: WARNING: No play method for volatile plugin spop Oct 21 11:01:10 volumio volumio[1203]: info: CoreCommandRouter::volumioVolatilePlay Oct 21 11:01:10 volumio volumio[1203]: info: CoreStateMachine::volatilePlay Oct 21 11:01:10 volumio volumio[1203]: info: WARNING: No play method for volatile plugin spop Oct 21 11:01:15 volumio volumio[1203]: info: CoreCommandRouter::volumioGetVisibleSources Oct 21 11:01:15 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 11:01:15 volumio volumio[1203]: info: CoreCommandRouter::volumioGetQueue Oct 21 11:01:15 volumio volumio[1203]: info: CoreStateMachine::getQueue Oct 21 11:01:15 volumio volumio[1203]: info: CorePlayQueue::getQueue Oct 21 11:01:18 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 21 11:01:21 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 21 11:01:26 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 21 11:01:26 volumio volumio[1203]: info: TuneIn handleBrowseUri: tunein/location Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 21 11:01:33 volumio volumio[1203]: info: TuneIn handleBrowseUri: tunein/local Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 21 11:01:33 volumio volumio[1203]: info: Discovery: Getting this device information Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::volumioGetState Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: network , getCachedPAddresses Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:01:33 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: system , getConf Oct 21 11:01:44 volumio volumio[1203]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 21 11:01:44 volumio volumio[1203]: info: CoreStateMachine::ClearQueue Oct 21 11:01:44 volumio volumio[1203]: info: CoreStateMachine::stop Oct 21 11:01:44 volumio volumio[1203]: info: CoreStateMachine::serviceStop Oct 21 11:01:44 volumio volumio[1203]: info: CoreCommandRouter::serviceStop Oct 21 11:01:44 volumio volumio[1203]: error: WARNING: No stop method for service spop Oct 21 11:01:44 volumio volumio[1203]: info: CorePlayQueue::clearPlayQueue Oct 21 11:01:44 volumio volumio[1203]: info: CorePlayQueue::saveQueue Oct 21 11:01:44 volumio volumio[1203]: info: CoreCommandRouter::volumioPushState Oct 21 11:01:44 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 11:01:44 volumio volumio[1203]: info: CoreCommandRouter::volumioPushQueue Oct 21 11:01:44 volumio volumio[1203]: info: CoreStateMachine::addQueueItems Oct 21 11:01:44 volumio volumio[1203]: info: CorePlayQueue::addQueueItems Oct 21 11:01:44 volumio volumio[1203]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s17755 Oct 21 11:01:44 volumio volumio[1203]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 21 11:01:45 volumio volumio[1203]: info: CorePlayQueue::saveQueue Oct 21 11:01:45 volumio volumio[1203]: info: CoreCommandRouter::volumioPushQueue Oct 21 11:01:45 volumio volumio[1203]: info: CoreStateMachine::updateTrackBlock Oct 21 11:01:45 volumio volumio[1203]: info: CorePlayQueue::getTrackBlock Oct 21 11:01:45 volumio volumio[1203]: info: CoreCommandRouter::volumioPlay Oct 21 11:01:45 volumio volumio[1203]: UNSET VOLATILE Oct 21 11:01:45 volumio volumio[1203]: [SpotifyConnect] unSetVolatile called Oct 21 11:01:45 volumio volumio[1203]: info: Spotify Unset Volatile called Oct 21 11:01:45 volumio volumio[1203]: [SpotifyConnect] Relinquishing Volumio State to another service Oct 21 11:01:45 volumio volumio[1203]: [SpotifyConnect] Spotify Received stop Oct 21 11:01:48 volumio volumio[1203]: info: CoreCommandRouter::volumioPlay Oct 21 11:01:48 volumio volumio[1203]: UNSET VOLATILE Oct 21 11:01:48 volumio volumio[1203]: [SpotifyConnect] unSetVolatile called Oct 21 11:01:48 volumio volumio[1203]: info: Spotify Unset Volatile called Oct 21 11:01:48 volumio volumio[1203]: [SpotifyConnect] Relinquishing Volumio State to another service Oct 21 11:01:48 volumio volumio[1203]: [SpotifyConnect] Spotify Received stop Oct 21 11:01:48 volumio volumio[1203]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 11:01:48 volumio volumio[1203]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Oct 21 11:01:48 volumio volumio[1203]: at Socket._healthCheck (dgram.js:638:11) Oct 21 11:01:48 volumio volumio[1203]: at Socket.send (dgram.js:435:8) Oct 21 11:01:48 volumio volumio[1203]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Oct 21 11:01:48 volumio volumio[1203]: at ControllerSpotify.stop (/data/plugins/music_service/spop/index.js:2674:19) Oct 21 11:01:48 volumio volumio[1203]: at ControllerSpotify.spotConnUnsetVolatile (/data/plugins/music_service/spop/index.js:2355:17) Oct 21 11:01:48 volumio volumio[1203]: at ControllerSpotify.unsetVol (/data/plugins/music_service/spop/index.js:68:21) Oct 21 11:01:48 volumio volumio[1203]: at CoreStateMachine.unSetVolatile (/volumio/app/statemachine.js:1507:27) Oct 21 11:01:48 volumio volumio[1203]: at CoreCommandRouter.volumioPlay (/volumio/app/index.js:1305:21) Oct 21 11:01:48 volumio volumio[1203]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:202:35) Oct 21 11:01:48 volumio volumio[1203]: at emitTwo (events.js:126:13) Oct 21 11:01:48 volumio volumio[1203]: at Socket.emit (events.js:214:7) Oct 21 11:01:48 volumio volumio[1203]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 21 11:01:48 volumio volumio[1203]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Oct 21 11:01:48 volumio volumio[1203]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 21 11:01:48 volumio volumio[1203]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 11:01:49 volumio sudo[6450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 11:00 Oct 21 11:01:49 volumio sudo[6450]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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" VOLUMIO_BUILD_VERSION="2c20ceacdd8365d3bd2fdff070d8c78700ff2903" VOLUMIO_FE_VERSION="fa55af43e5eb642e968aa6de2f8a985962941d63" VOLUMIO_BE_VERSION="c9e4849495e810bd6ebb6833232daba25d86dd4c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Nov 20 20:05:14 CET 2020" VOLUMIO_VERSION="2.853" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="f7f9725bb5ce7d6da77135f468c28964"