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