-- Logs begin at Fri 2022-09-23 20:12:02 UTC, end at Sat 2024-04-20 15:25:26 UTC. --
Apr 20 15:25:24 minidsp-shd volumio[995]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443"
Apr 20 15:25:24 minidsp-shd volumio[995]: [Vollibrespot] : Authenticated as "rob_fabbri" !
Apr 20 15:25:24 minidsp-shd volumio[995]: [Vollibrespot] : Using alsa sink
Apr 20 15:25:24 minidsp-shd volumio[995]: [Vollibrespot] : Metadata pipe established
Apr 20 15:25:24 minidsp-shd volumio[995]: [Vollibrespot] : Country: "IT"
Apr 20 15:25:24 minidsp-shd volumio[995]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 }
Apr 20 15:25:24 minidsp-shd volumio[760]: [SpotifyConnect] Volume Spotify: 32.99916075379568 Volumio: 33
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Event: SessionActive { became_active_at: 1713626725290 }
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Fetching autoplay context uri
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : SessionActive!
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 90371859534969545957087389843944132948, audio_type: Track } }
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 90371859534969545957087389843944132948, audio_type: Track } }
Apr 20 15:25:25 minidsp-shd volumio[760]: [SpotifyConnect] A connect session has begun
Apr 20 15:25:25 minidsp-shd volumio[760]: info: Acquiring new spotify session
Apr 20 15:25:25 minidsp-shd volumio[760]: info: CoreCommandRouter::volumioStop
Apr 20 15:25:25 minidsp-shd volumio[760]: info: CoreStateMachine::stop
Apr 20 15:25:25 minidsp-shd volumio[760]: info: CoreStateMachine::serviceStop
Apr 20 15:25:25 minidsp-shd volumio[760]: info: CoreCommandRouter::serviceStop
Apr 20 15:25:25 minidsp-shd volumio[760]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 20 15:25:25 minidsp-shd volumio[760]: TypeError: Cannot read property 'then' of undefined
Apr 20 15:25:25 minidsp-shd volumio[760]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31)
Apr 20 15:25:25 minidsp-shd volumio[760]: at emitNone (events.js:106:13)
Apr 20 15:25:25 minidsp-shd volumio[760]: at SpotConnEvents.emit (events.js:208:7)
Apr 20 15:25:25 minidsp-shd volumio[760]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16)
Apr 20 15:25:25 minidsp-shd volumio[760]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12)
Apr 20 15:25:25 minidsp-shd volumio[760]: at emitTwo (events.js:126:13)
Apr 20 15:25:25 minidsp-shd volumio[760]: at Socket.emit (events.js:214:7)
Apr 20 15:25:25 minidsp-shd volumio[760]: at UDP.onMessage [as onmessage] (dgram.js:659:8)
Apr 20 15:25:25 minidsp-shd volumio[760]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : error 403 for uri hm://keymaster/token/authenticated?client_id=4ced2e7c73484127be5d36015752eb43&scope=streaming,user-read-playback-state,user-modify-playback-state,user-read-currently-playing,user-read-private
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Error: MercuryError
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:album:1Ukey8wyz69bE1CrhsyOG3">
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : Resolving uri "spotify:station:album:1Ukey8wyz69bE1CrhsyOG3"
Apr 20 15:25:25 minidsp-shd volumio[995]: thread 'main' panicked at 'cannot poll Map twice', /build/cache/registry/src/github.com-1ecc6299db9ec823/futures-0.1.29/src/future/map.rs:35:29
Apr 20 15:25:25 minidsp-shd volumio[995]: stack backtrace:
Apr 20 15:25:25 minidsp-shd volumio[995]: 0: 0x9b333c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842
Apr 20 15:25:25 minidsp-shd volumio[995]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86
Apr 20 15:25:25 minidsp-shd volumio[995]: 1: 0x9b333c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47
Apr 20 15:25:25 minidsp-shd volumio[995]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66
Apr 20 15:25:25 minidsp-shd volumio[995]: 2: 0x9b333c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/sys_common/backtrace.rs:78
Apr 20 15:25:25 minidsp-shd volumio[995]: 3: 0x9b333c - ::fmt::he1a5d6f378e506c4
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/sys_common/backtrace.rs:59
Apr 20 15:25:25 minidsp-shd volumio[995]: 4: 0x9d3d5c - core::fmt::write::hb37ae5a5e0b70623
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libcore/fmt/mod.rs:1076
Apr 20 15:25:25 minidsp-shd volumio[995]: 5: 0x9acc64 - std::io::Write::write_fmt::ha24bb3f5a858327b
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/io/mod.rs:1537
Apr 20 15:25:25 minidsp-shd volumio[995]: 6: 0x9b5974 - std::sys_common::backtrace::_print::h47b03aa1342833e3
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/sys_common/backtrace.rs:62
Apr 20 15:25:25 minidsp-shd volumio[995]: 7: 0x9b5974 - std::sys_common::backtrace::print::h2217cbc390250439
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/sys_common/backtrace.rs:49
Apr 20 15:25:25 minidsp-shd volumio[995]: 8: 0x9b5974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panicking.rs:198
Apr 20 15:25:25 minidsp-shd volumio[995]: 9: 0x9b5640 - std::panicking::default_hook::h46ab82039cbc65eb
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panicking.rs:217
Apr 20 15:25:25 minidsp-shd volumio[995]: 10: 0x9b6054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panicking.rs:526
Apr 20 15:25:25 minidsp-shd volumio[995]: 11: 0x9b5c54 - rust_begin_unwind
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panicking.rs:437
Apr 20 15:25:25 minidsp-shd volumio[995]: 12: 0x9d1594 - core::panicking::panic_fmt::ha292e19d5ae716ed
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libcore/panicking.rs:85
Apr 20 15:25:25 minidsp-shd volumio[995]: 13: 0x9d1370 - core::option::expect_failed::he9e39f8f5ba60ecb
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libcore/option.rs:1261
Apr 20 15:25:25 minidsp-shd volumio[995]: 14: 0x6a7bf8 - as futures::future::Future>::poll::h3d56b212ca27d9e4
Apr 20 15:25:25 minidsp-shd volumio[995]: 15: 0x55d3a4 - ::poll::ha2e91a6b75c719f3
Apr 20 15:25:25 minidsp-shd volumio[995]: 16: 0x4d3ca4 - ::poll::hc5e7d2d1b7dbef13
Apr 20 15:25:25 minidsp-shd volumio[995]: 17: 0x49b844 - futures::task_impl::std::set::h8f081cf0436110c9
Apr 20 15:25:25 minidsp-shd volumio[995]: 18: 0x4af41c - std::thread::local::LocalKey::with::h711a13323aafc45a
Apr 20 15:25:25 minidsp-shd volumio[995]: 19: 0x48ba50 - tokio_current_thread::Entered::block_on::h339073902a399eb7
Apr 20 15:25:25 minidsp-shd volumio[995]: 20: 0x4afe00 - std::thread::local::LocalKey::with::hff64c13ccfec5327
Apr 20 15:25:25 minidsp-shd volumio[995]: 21: 0x4bc20c - tokio_reactor::with_default::h7ef12e65cb103d2f
Apr 20 15:25:25 minidsp-shd volumio[995]: 22: 0x48f9f8 - tokio::runtime::current_thread::runtime::Runtime::block_on::h043f136d6b8cf48f
Apr 20 15:25:25 minidsp-shd volumio[995]: 23: 0x4d52c8 - vollibrespot::main::h02a0d49d4cc5eaa6
Apr 20 15:25:25 minidsp-shd volumio[995]: 24: 0x4bcf18 - std::rt::lang_start::{{closure}}::h279cd2b7929fdba7
Apr 20 15:25:25 minidsp-shd volumio[995]: 25: 0x9b6404 - std::rt::lang_start_internal::{{closure}}::he93bfc404849b78a
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/rt.rs:52
Apr 20 15:25:25 minidsp-shd volumio[995]: 26: 0x9b6404 - std::panicking::try::do_call::h6e9e98f4078affb0
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panicking.rs:348
Apr 20 15:25:25 minidsp-shd volumio[995]: 27: 0x9b6404 - std::panicking::try::h2e68d4f7f799a6df
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panicking.rs:325
Apr 20 15:25:25 minidsp-shd volumio[995]: 28: 0x9b6404 - std::panic::catch_unwind::h8880a4c07cc66391
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/panic.rs:394
Apr 20 15:25:25 minidsp-shd volumio[995]: 29: 0x9b6404 - std::rt::lang_start_internal::hf4ae2140248bf16b
Apr 20 15:25:25 minidsp-shd volumio[995]: at src/libstd/rt.rs:51
Apr 20 15:25:25 minidsp-shd volumio[995]: 30: 0x4d5d84 - main
Apr 20 15:25:25 minidsp-shd volumio[995]: 31: 0xf79444aa - __libc_start_main
Apr 20 15:25:25 minidsp-shd systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a
Apr 20 15:25:25 minidsp-shd systemd[1]: Unit volspotconnect2.service entered failed state.
Apr 20 15:25:25 minidsp-shd volumio[995]: [Vollibrespot] : EventSender disconnected
Apr 20 15:25:26 minidsp-shd sudo[5644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-20 15:24
Apr 20 15:25:26 minidsp-shd sudo[5644]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 8 (jessie)"
NAME="Debian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=debian
HOME_URL="http://www.debian.org/"
SUPPORT_URL="http://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="6d9442f761618503268d0366db805a252514dec1"
VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="minidspshd"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Jul 26 13:26:42 CEST 2022"
VOLUMIO_VERSION="1.125"
VOLUMIO_HARDWARE="nanopineo2"
VOLUMIO_VENDOR="miniDSP"
VOLUMIO_MODEL="SHD"
VOLUMIO_VENDOR_MODEL="miniDSP SHD"
VOLUMIO_HASH="9c17c7c45298f5ca9f5ec80e116295fd"