::block_on::h2e4ff2c13a247d3c Apr 03 20:37:27 volumiodacmini volumio[848]: 26: 0x55b298 - std::thread::local::LocalKey::with::h663fcb2d9f4b6c18 Apr 03 20:37:27 volumiodacmini volumio[848]: 27: 0x5685d4 - tokio_reactor::with_default::h5d8f90b2b875599a Apr 03 20:37:27 volumiodacmini volumio[848]: 28: 0x53a940 - tokio::runtime::current_thread::runtime::Runtime::block_on::h9505b8c1bab44fab Apr 03 20:37:27 volumiodacmini volumio[848]: 29: 0x581f98 - vollibrespot::main::h3614a8e14a6045df Apr 03 20:37:27 volumiodacmini volumio[848]: 30: 0x5692e0 - std::rt::lang_start::{{closure}}::h0a25e89da98ec7db Apr 03 20:37:27 volumiodacmini volumio[848]: 31: 0xa7672c - std::rt::lang_start_internal::{{closure}}::h97efd249046dc8e8 Apr 03 20:37:27 volumiodacmini volumio[848]: at src/libstd/rt.rs:52 Apr 03 20:37:27 volumiodacmini volumio[848]: 32: 0xa7672c - std::panicking::try::do_call::h3d07de510dcbc7c8 Apr 03 20:37:27 volumiodacmini volumio[848]: at src/libstd/panicking.rs:348 Apr 03 20:37:27 volumiodacmini volumio[848]: 33: 0xa7672c - std::panicking::try::hc25ddf66eb022203 Apr 03 20:37:27 volumiodacmini volumio[848]: at src/libstd/panicking.rs:325 Apr 03 20:37:27 volumiodacmini volumio[848]: 34: 0xa7672c - std::panic::catch_unwind::h36b06962268095b4 Apr 03 20:37:27 volumiodacmini volumio[848]: at src/libstd/panic.rs:394 Apr 03 20:37:27 volumiodacmini volumio[848]: 35: 0xa7672c - std::rt::lang_start_internal::hdbd4407cfdcc64c8 Apr 03 20:37:27 volumiodacmini volumio[848]: at src/libstd/rt.rs:51 Apr 03 20:37:27 volumiodacmini volumio[848]: 36: 0x582a54 - main Apr 03 20:37:27 volumiodacmini volumio[848]: 37: 0xb6c5e294 - __libc_start_main Apr 03 20:37:28 volumiodacmini avahi-daemon[530]: Invalid response packet from host 192.168.1.110. Apr 03 20:37:29 volumiodacmini systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Apr 03 20:37:29 volumiodacmini systemd[1]: Stopping Volspotconnect2 Daemon... Apr 03 20:37:29 volumiodacmini systemd[1]: Starting Volspotconnect2 Daemon... Apr 03 20:37:29 volumiodacmini systemd[1]: Started Volspotconnect2 Daemon. Apr 03 20:37:29 volumiodacmini volumio[1316]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Apr 03 20:37:29 volumiodacmini volumio[1316]: Reading Config from "volspotify.toml" Apr 03 20:37:29 volumiodacmini volumio[1316]: [Vollibrespot] : Using Alsa backend with device: hw:0 Apr 03 20:37:29 volumiodacmini volumio[1316]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 03 20:37:38 volumiodacmini volumio[1316]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Apr 03 20:37:38 volumiodacmini volumio[1316]: [Vollibrespot] : Authenticated as "gwenael.roinet" ! Apr 03 20:37:38 volumiodacmini volumio[1316]: [Vollibrespot] : Using alsa sink Apr 03 20:37:38 volumiodacmini volumio[1316]: [Vollibrespot] : Country: "FR" Apr 03 20:37:38 volumiodacmini volumio[1316]: [Vollibrespot] : Metadata pipe established Apr 03 20:37:38 volumiodacmini volumio[1316]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Apr 03 20:37:38 volumiodacmini volumio[724]: [SpotifyConnect] 100 Apr 03 20:37:38 volumiodacmini volumio[724]: [SpotifyConnect] Volume: Spotify:100 Volumio: 100 Apr 03 20:37:38 volumiodacmini volumio[724]: info: CoreStateMachine::pushState Apr 03 20:37:38 volumiodacmini volumio[724]: info: CoreStateMachine::getState Apr 03 20:37:38 volumiodacmini volumio[724]: info: CoreCommandRouter::volumioPushState Apr 03 20:37:38 volumiodacmini volumio[724]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 20:37:39 volumiodacmini volumio[1316]: [Vollibrespot] : Event: SessionActive { became_active_at: 1775248659098 } Apr 03 20:37:39 volumiodacmini volumio[1316]: [Vollibrespot] : SessionActive! Apr 03 20:37:39 volumiodacmini volumio[1316]: [Vollibrespot] : Fetching autoplay context uri Apr 03 20:37:39 volumiodacmini volumio[1316]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 290102600611271966959799725498111058192, audio_type: Track } } Apr 03 20:37:39 volumiodacmini volumio[1316]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 290102600611271966959799725498111058192, audio_type: Track } } Apr 03 20:37:39 volumiodacmini volumio[724]: [SpotifyConnect] A connect session has begun Apr 03 20:37:39 volumiodacmini volumio[1316]: [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 03 20:37:39 volumiodacmini volumio[1316]: [Vollibrespot] : Error: MercuryError Apr 03 20:37:39 volumiodacmini volumio[1316]: 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 03 20:37:39 volumiodacmini volumio[1316]: stack backtrace: Apr 03 20:37:39 volumiodacmini sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 20:37:39 volumiodacmini volumio[724]: [SpotifyConnect] Device palyback is active! Apr 03 20:37:39 volumiodacmini sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 20:37:39 volumiodacmini volumio[724]: info: CoreCommandRouter::volumioGetState Apr 03 20:37:39 volumiodacmini volumio[724]: info: CoreStateMachine::getState Apr 03 20:37:39 volumiodacmini volumio[724]: [SpotifyConnect] Currently active: airplay Apr 03 20:37:39 volumiodacmini volumio[724]: [SpotifyConnect] Stopping currently active service Apr 03 20:37:39 volumiodacmini volumio[724]: info: CoreCommandRouter::volumioStop Apr 03 20:37:39 volumiodacmini volumio[724]: info: CoreStateMachine::stop Apr 03 20:37:39 volumiodacmini volumio[724]: info: CoreStateMachine::serviceStop Apr 03 20:37:39 volumiodacmini volumio[724]: info: CoreCommandRouter::serviceStop Apr 03 20:37:39 volumiodacmini volumio[724]: error: WARNING: No stop method for service airplay Apr 03 20:37:39 volumiodacmini volumio[724]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 20:37:39 volumiodacmini volumio[724]: TypeError: Cannot read property 'then' of undefined Apr 03 20:37:39 volumiodacmini volumio[724]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Apr 03 20:37:39 volumiodacmini volumio[724]: at emitNone (events.js:106:13) Apr 03 20:37:39 volumiodacmini volumio[724]: at SpotConnEvents.emit (events.js:208:7) Apr 03 20:37:39 volumiodacmini volumio[724]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Apr 03 20:37:39 volumiodacmini volumio[724]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Apr 03 20:37:39 volumiodacmini volumio[724]: at emitTwo (events.js:126:13) Apr 03 20:37:39 volumiodacmini volumio[724]: at Socket.emit (events.js:214:7) Apr 03 20:37:39 volumiodacmini volumio[724]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Apr 03 20:37:39 volumiodacmini sudo[1334]: pam_unix(sudo:session): session closed for user root Apr 03 20:37:39 volumiodacmini volumio[724]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 20:37:40 volumiodacmini volumio[1316]: 0: 0xa9160c - backtrace::backtrace::libunwind::trace::hf9eeeb1d52578408 Apr 03 20:37:40 volumiodacmini volumio[1316]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Apr 03 20:37:40 volumiodacmini volumio[1316]: 1: 0xa9160c - backtrace::backtrace::trace_unsynchronized::h9d71662eece903ab Apr 03 20:37:40 volumiodacmini volumio[1316]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Apr 03 20:37:40 volumiodacmini volumio[1316]: 2: 0xa9160c - std::sys_common::backtrace::_print_fmt::h54695504f8601726 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/sys_common/backtrace.rs:78 Apr 03 20:37:40 volumiodacmini volumio[1316]: 3: 0xa9160c - ::fmt::h512ecf96415d8ce2 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/sys_common/backtrace.rs:59 Apr 03 20:37:40 volumiodacmini volumio[1316]: 4: 0xab6218 - core::fmt::write::h1060f85d3ee53644 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libcore/fmt/mod.rs:1076 Apr 03 20:37:40 volumiodacmini volumio[1316]: 5: 0xa8aee0 - std::io::Write::write_fmt::h34e2a8730bb7a876 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/io/mod.rs:1537 Apr 03 20:37:40 volumiodacmini volumio[1316]: 6: 0xa93c88 - std::sys_common::backtrace::_print::h2be13b0c8dff2c9b Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/sys_common/backtrace.rs:62 Apr 03 20:37:40 volumiodacmini volumio[1316]: 7: 0xa93c88 - std::sys_common::backtrace::print::hd763058df6eec412 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/sys_common/backtrace.rs:49 Apr 03 20:37:40 volumiodacmini volumio[1316]: 8: 0xa93c88 - std::panicking::default_hook::{{closure}}::h48b9472d8fc0e6bf Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/panicking.rs:198 Apr 03 20:37:40 volumiodacmini volumio[1316]: 9: 0xa9393c - std::panicking::default_hook::h0fc1c5596bab0152 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/panicking.rs:217 Apr 03 20:37:40 volumiodacmini volumio[1316]: 10: 0xa94368 - std::panicking::rust_panic_with_hook::hae7ba177154e198d Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/panicking.rs:526 Apr 03 20:37:40 volumiodacmini volumio[1316]: 11: 0xa93f68 - rust_begin_unwind Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libstd/panicking.rs:437 Apr 03 20:37:40 volumiodacmini volumio[1316]: 12: 0xab38f0 - core::panicking::panic_fmt::hf855e45b7cc21a3f Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libcore/panicking.rs:85 Apr 03 20:37:40 volumiodacmini systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a Apr 03 20:37:40 volumiodacmini systemd[1]: Unit volspotconnect2.service entered failed state. Apr 03 20:37:40 volumiodacmini volumio[1316]: 13: 0xab36cc - core::option::expect_failed::h2665e29771a01623 Apr 03 20:37:40 volumiodacmini volumio[1316]: at src/libcore/option.rs:1261 Apr 03 20:37:40 volumiodacmini volumio[1316]: 14: 0x77c9d8 - as futures::future::Future>::poll::h4c2a9a21ba09d56f Apr 03 20:37:40 volumiodacmini volumio[1316]: 15: 0x62a448 - ::poll::h6201a2b9faae04af Apr 03 20:37:40 volumiodacmini volumio[1316]: 16: 0x59e90c - ::poll::h2c608b7bbd5483b9 Apr 03 20:37:40 volumiodacmini volumio[1316]: 17: 0x565028 - futures::task_impl::std::set::he0ba9d7a117059e7 Apr 03 20:37:40 volumiodacmini volumio[1316]: 18: 0x579454 - std::thread::local::LocalKey::with::h69e67cf00223a2ba Apr 03 20:37:40 volumiodacmini volumio[1316]: 19: 0x554718 - tokio_current_thread::Entered