-- Logs begin at Sat 2024-05-04 09:54:25 UTC, end at Sat 2024-05-04 09:57:28 UTC. --
May 04 09:56:01 hifi-fasound volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin
May 04 09:56:01 hifi-fasound volumio[1016]: info: [GPIO_Control] UI Config file /data/plugins/system_controller/gpio_control/UIConfig.json
May 04 09:56:09 hifi-fasound volumio[1016]: info: CALLMETHOD: system_controller gpio_control saveConfig [object Object]
May 04 09:56:09 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: gpio_control , saveConfig
May 04 09:56:09 hifi-fasound volumio[1016]: info: [GPIO_Control] Saving config
May 04 09:56:09 hifi-fasound volumio[1016]: info: [GPIO_Control] Reading config and creating GPIOs
May 04 09:56:09 hifi-fasound volumio[1016]: info: [GPIO_Control] Will set GPIO 15 On when systemStartup
May 04 09:56:09 hifi-fasound volumio[1016]: info: [GPIO_Control] Turning GPIO 15 On (systemStartup)
May 04 09:56:09 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioGetState
May 04 09:56:09 hifi-fasound volumio[1016]: info: CorePlayQueue::getTrack 0
May 04 09:56:24 hifi-fasound volumio[1016]: info: Starting Uninstall of plugin music_service - spop
May 04 09:56:24 hifi-fasound volumio[1016]: info: Uninstalling plugin spop
May 04 09:56:24 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify
May 04 09:56:24 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 04 09:56:24 hifi-fasound volumio[1016]: [SpotifyConnect] Closing metadata listener
May 04 09:56:24 hifi-fasound volumio[1016]: info: Disabling plugin spop
May 04 09:56:24 hifi-fasound volumio[1016]: info: Checking if uninstall.sh is present
May 04 09:56:24 hifi-fasound sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service
May 04 09:56:24 hifi-fasound volumio[1016]: info: Executing uninstall.sh
May 04 09:56:24 hifi-fasound sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:56:24 hifi-fasound systemd[1]: Stopping Volspotconnect2 Daemon...
May 04 09:56:24 hifi-fasound systemd[1]: Stopped Volspotconnect2 Daemon.
May 04 09:56:24 hifi-fasound sudo[1710]: pam_unix(sudo:session): session closed for user root
May 04 09:56:24 hifi-fasound volumio[1016]: [SpotifyConnect] Vollibrespot Daemon service stoped!
May 04 09:56:24 hifi-fasound sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh
May 04 09:56:24 hifi-fasound sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:56:24 hifi-fasound sudo[1717]: pam_unix(sudo:session): session closed for user root
May 04 09:56:24 hifi-fasound volumio[1016]: info: Uninstall script completed
May 04 09:56:24 hifi-fasound volumio[1016]: info: Removing plugin spop from configuration
May 04 09:56:24 hifi-fasound volumio[1016]: info: Successfully removed spop configuration files
May 04 09:56:24 hifi-fasound volumio[1016]: info: Plugin folders cleanup
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning into folder /volumio/app/plugins/
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category audio_interface
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category miscellanea
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category music_service
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category plugins.json
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category system_controller
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category user_interface
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning into folder /data/plugins/
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category miscellanea
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category music_service
May 04 09:56:24 hifi-fasound volumio[1016]: info: Cleaning folder for spop
May 04 09:56:24 hifi-fasound volumio[1016]: info: Scanning category system_controller
May 04 09:56:24 hifi-fasound volumio[1016]: info: Plugin folders cleanup completed
May 04 09:56:24 hifi-fasound volumio[1016]: info: [{"prettyName":"Touch Display","name":"touch_display","category":"miscellanea","version":"1.3.0","icon":"fa-hand-pointer-o","enabled":true,"active":true},{"prettyName":"CD Player","name":"cdplayer","category":"music_service","version":"1.0.0","icon":"fa-music","enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_controller","version":"0.8.1","icon":"fa-microchip","enabled":true,"active":true},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_controller","version":"0.0.1","icon":"fa-microchip","enabled":true,"active":true}]
May 04 09:56:34 hifi-fasound volumio[1016]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/spop/spop.zip
May 04 09:56:39 hifi-fasound volumio[1016]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/spop/spop.zip
May 04 09:56:39 hifi-fasound volumio[1016]: info: Folder /tmp/plugins removed
May 04 09:56:40 hifi-fasound volumio[1016]: info: Checking if plugin already exists
May 04 09:56:40 hifi-fasound volumio[1016]: info: Rename folder
May 04 09:56:40 hifi-fasound volumio[1016]: info: Folder /tmp/downloaded_plugin.zip removed
May 04 09:56:40 hifi-fasound volumio[1016]: info: Move to category
May 04 09:56:43 hifi-fasound volumio[1016]: info: Checking if install.sh is present
May 04 09:56:43 hifi-fasound volumio[1016]: info: Executing install.sh
May 04 09:56:43 hifi-fasound sudo[1779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh
May 04 09:56:43 hifi-fasound sudo[1779]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:56:43 hifi-fasound volumio[1016]: info: Writing systemd unit
May 04 09:56:43 hifi-fasound sudo[1779]: pam_unix(sudo:session): session closed for user root
May 04 09:56:43 hifi-fasound volumio[1016]: info: Setting permissions
May 04 09:56:43 hifi-fasound volumio[1016]: info: Writing startconnect unit
May 04 09:56:43 hifi-fasound volumio[1016]: Plugin install end detected on script
May 04 09:56:43 hifi-fasound volumio[1016]: info: Install script completed
May 04 09:56:43 hifi-fasound volumio[1016]: info: Adding reference to registry
May 04 09:56:43 hifi-fasound volumio[1016]: info: Done installing plugin.
May 04 09:56:43 hifi-fasound volumio[1016]: info: Folder /tmp/plugins removed
May 04 09:56:43 hifi-fasound volumio[1016]: info: Folder /tmp/downloaded_plugin.zip removed
May 04 09:56:43 hifi-fasound volumio[1016]: info: Folder /data/temp removed
May 04 09:56:44 hifi-fasound volumio[1016]: info: Enabling plugin spop
May 04 09:56:44 hifi-fasound volumio[1016]: info: Loading plugin "spop"...
May 04 09:56:44 hifi-fasound volumio[1016]: info: PLUGIN START: spop
May 04 09:56:44 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 04 09:56:44 hifi-fasound volumio[1016]: [SpotifyConnect] Creating VLS config file
May 04 09:56:44 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:56:44 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:56:44 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:56:44 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:56:44 hifi-fasound volumio[1016]: [SpotifyConnect] Starting metadata listener
May 04 09:56:44 hifi-fasound sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
May 04 09:56:44 hifi-fasound sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:56:44 hifi-fasound sudo[1796]: pam_unix(sudo:session): session closed for user root
May 04 09:56:44 hifi-fasound sudo[1800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
May 04 09:56:44 hifi-fasound sudo[1800]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:56:44 hifi-fasound systemd[1]: Starting Volspotconnect2 Daemon...
May 04 09:56:44 hifi-fasound systemd[1]: Started Volspotconnect2 Daemon.
May 04 09:56:44 hifi-fasound volumio[1810]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 04 09:56:44 hifi-fasound volumio[1810]: Reading Config from "/tmp/volspotify.toml"
May 04 09:56:44 hifi-fasound volumio[1810]: [Vollibrespot] : Using Alsa backend with device: softvolume
May 04 09:56:44 hifi-fasound volumio[1810]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
May 04 09:56:44 hifi-fasound sudo[1800]: pam_unix(sudo:session): session closed for user root
May 04 09:56:44 hifi-fasound volumio[1016]: [SpotifyConnect] Vollibrespot Daemon service started!
May 04 09:56:44 hifi-fasound volumio[1016]: [Metrics] SpotifyConnect: 0s 141.19ms
May 04 09:56:44 hifi-fasound volumio[1016]: info: Done.
May 04 09:56:47 hifi-fasound volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin
May 04 09:56:48 hifi-fasound volumio[1016]: info: Checking Spotify Web API
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Authenticated as "1opqbvca26fsd4g60igcyctv5" !
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Using alsa sink
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Country: "TW"
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Metadata pipe established
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 }
May 04 09:56:58 hifi-fasound volumio[1016]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Fetching autoplay context uri
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714816618891 }
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : SessionActive!
May 04 09:56:58 hifi-fasound volumio[1016]: [SpotifyConnect] A connect session has begun
May 04 09:56:58 hifi-fasound volumio[1016]: info: Acquiring new spotify session
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 119970865213098212147211882846637349857, audio_type: Track } }
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 119970865213098212147211882846637349857, audio_type: Track } }
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioStop
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreStateMachine::stop
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined
May 04 09:56:58 hifi-fasound volumio[1016]: verbose: UNSET VOLATILE
May 04 09:56:58 hifi-fasound volumio[1016]: info: Checking Spotify Web API
May 04 09:56:58 hifi-fasound volumio[1016]: [SpotifyConnect] Vollibrespot Active
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreStateMachine::setConsumeUpdateService undefined
May 04 09:56:58 hifi-fasound volumio[1016]: info: [1714816618895] ControllerSpotify::pushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::servicePushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreStateMachine::pushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioPushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 04 09:56:58 hifi-fasound volumio[1016]: [SpotifyConnect] Device palyback is active!
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : error 403 for uri hm://keymaster/token/authenticated?client_id=ecd10c0393ec4a36bae1661bbbfd2e1d&scope=streaming,user-read-playback-state,user-modify-playback-state,user-read-currently-playing,user-read-private
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : Error: MercuryError
May 04 09:56:58 hifi-fasound volumio[1810]: thread 'main' panicked at 'cannot poll Map twice', /home/volumio/.cargo/registry/src/github.com-1285ae84e5963aae/futures-0.1.31/src/future/map.rs:35:29
May 04 09:56:58 hifi-fasound volumio[1810]: stack backtrace:
May 04 09:56:58 hifi-fasound volumio[1810]: 0: 0x993c40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
May 04 09:56:58 hifi-fasound volumio[1810]: 1: 0x993c40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
May 04 09:56:58 hifi-fasound volumio[1810]: 2: 0x993c40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5
May 04 09:56:58 hifi-fasound volumio[1810]: 3: 0x993c40 - ::fmt::hf1c91d83d30a63b5
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22
May 04 09:56:58 hifi-fasound volumio[1810]: 4: 0x9b9d68 - core::fmt::write::he7b81f119cac9e82
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17
May 04 09:56:58 hifi-fasound volumio[1810]: 5: 0x98d5d4 - std::io::Write::write_fmt::hd6a547671801193b
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15
May 04 09:56:58 hifi-fasound volumio[1810]: 6: 0x995728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5
May 04 09:56:58 hifi-fasound volumio[1810]: 7: 0x995728 - std::sys_common::backtrace::print::hb4deb0b578e6c939
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9
May 04 09:56:58 hifi-fasound volumio[1810]: 8: 0x995728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22
May 04 09:56:58 hifi-fasound volumio[1810]: 9: 0x99523c - std::panicking::default_hook::h3b931251a9c4b1f6
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9
May 04 09:56:58 hifi-fasound volumio[1810]: 10: 0x995f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17
May 04 09:56:58 hifi-fasound volumio[1810]: 11: 0x995c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13
May 04 09:56:58 hifi-fasound volumio[1810]: 12: 0x9941bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18
May 04 09:56:58 hifi-fasound volumio[1810]: 13: 0x9959ec - rust_begin_unwind
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5
May 04 09:56:58 hifi-fasound volumio[1810]: 14: 0x478e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14
May 04 09:56:58 hifi-fasound volumio[1810]: 15: 0x9b6b40 - core::panicking::panic_display::h7abbe07d495b3a32
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:72:5
May 04 09:56:58 hifi-fasound volumio[1810]: 16: 0x9b6af0 - core::panicking::panic_str::h61704b1b7d988fea
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:56:5
May 04 09:56:58 hifi-fasound volumio[1810]: 17: 0x478cb0 - core::option::expect_failed::h3c9740a4df357423
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/option.rs:1854:5
May 04 09:56:58 hifi-fasound volumio[1810]: 18: 0x6da388 - as futures::future::Future>::poll::h03b0468e31b39462
May 04 09:56:58 hifi-fasound volumio[1810]: 19: 0x569950 - ::poll::h980bb2790cc3c82d
May 04 09:56:58 hifi-fasound volumio[1810]: 20: 0x4a2598 - ::poll::h06f390c1cf340695
May 04 09:56:58 hifi-fasound volumio[1810]: 21: 0x4b918c - futures::task_impl::std::set::h917345edbb2d7b17
May 04 09:56:58 hifi-fasound volumio[1810]: 22: 0x4daf24 - std::thread::local::LocalKey::with::hebf033a3f9f19674
May 04 09:56:58 hifi-fasound volumio[1810]: 23: 0x4a9924 - tokio_current_thread::Entered::block_on::h34935ca5acdeef78
May 04 09:56:58 hifi-fasound volumio[1810]: 24: 0x4dabac - std::thread::local::LocalKey::with::hb9ef5b7276039152
May 04 09:56:58 hifi-fasound volumio[1810]: 25: 0x4c9604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d
May 04 09:56:58 hifi-fasound volumio[1810]: 26: 0x498da4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c
May 04 09:56:58 hifi-fasound volumio[1810]: 27: 0x4a39a0 - vollibrespot::main::haa33a046348d736f
May 04 09:56:58 hifi-fasound volumio[1810]: 28: 0x4c16fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897
May 04 09:56:58 hifi-fasound volumio[1810]: 29: 0x4d9278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10
May 04 09:56:58 hifi-fasound volumio[1810]: 30: 0x986bcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13
May 04 09:56:58 hifi-fasound volumio[1810]: 31: 0x986bcc - std::panicking::try::do_call::h7a5efa020212c0f3
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40
May 04 09:56:58 hifi-fasound volumio[1810]: 32: 0x986bcc - std::panicking::try::h3145f518af029892
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19
May 04 09:56:58 hifi-fasound volumio[1810]: 33: 0x986bcc - std::panic::catch_unwind::h876d78503e730edb
May 04 09:56:58 hifi-fasound volumio[1016]: [SpotifyConnect] Device palyback is inactive
May 04 09:56:58 hifi-fasound volumio[1016]: [SpotifyConnect] Sink released
May 04 09:56:58 hifi-fasound volumio[1016]: info: [1714816618974] ControllerSpotify::pushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::servicePushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreStateMachine::pushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioPushState
May 04 09:56:58 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14
May 04 09:56:58 hifi-fasound volumio[1810]: 34: 0x986bcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48
May 04 09:56:58 hifi-fasound volumio[1810]: 35: 0x986bcc - std::panicking::try::do_call::h6affa17582fe803e
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40
May 04 09:56:58 hifi-fasound volumio[1810]: 36: 0x986bcc - std::panicking::try::hf3254f9f909f508b
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19
May 04 09:56:58 hifi-fasound volumio[1810]: 37: 0x986bcc - std::panic::catch_unwind::h17abded8b6d92414
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14
May 04 09:56:58 hifi-fasound volumio[1810]: 38: 0x986bcc - std::rt::lang_start_internal::h62925866154f59e6
May 04 09:56:58 hifi-fasound volumio[1810]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20
May 04 09:56:58 hifi-fasound volumio[1810]: 39: 0x4a411c - main
May 04 09:56:58 hifi-fasound volumio[1810]: 40: 0xb6c3e678 - __libc_start_main
May 04 09:56:58 hifi-fasound volumio[1810]: [Vollibrespot] : EventSender disconnected
May 04 09:56:58 hifi-fasound volumio[1016]: info: CorePlayQueue::getTrack 0
May 04 09:56:58 hifi-fasound volumio[1016]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
May 04 09:56:59 hifi-fasound volumio[1016]: info: touch_display: Setting screensaver timeout to 0 seconds.
May 04 09:56:59 hifi-fasound volumio[1016]: info: touch_display: Setting screensaver timeout to 0 seconds.
May 04 09:56:59 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioGetState
May 04 09:56:59 hifi-fasound volumio[1016]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
May 04 09:56:59 hifi-fasound volumio[1016]: info: touch_display: Setting screensaver timeout to 0 seconds.
May 04 09:57:01 hifi-fasound systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
May 04 09:57:01 hifi-fasound systemd[1]: Stopping Volspotconnect2 Daemon...
May 04 09:57:01 hifi-fasound systemd[1]: Starting Volspotconnect2 Daemon...
May 04 09:57:01 hifi-fasound systemd[1]: Started Volspotconnect2 Daemon.
May 04 09:57:01 hifi-fasound volumio[1867]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 04 09:57:01 hifi-fasound volumio[1867]: Reading Config from "/tmp/volspotify.toml"
May 04 09:57:01 hifi-fasound volumio[1867]: [Vollibrespot] : Using Alsa backend with device: softvolume
May 04 09:57:01 hifi-fasound volumio[1867]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 04 09:57:10 hifi-fasound volumio[1016]: info: Getting Alsa Cards List without I2S DAC
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
May 04 09:57:10 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 04 09:57:21 hifi-fasound volumio[1016]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object]
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioGetState
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions
May 04 09:57:21 hifi-fasound sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 04 09:57:21 hifi-fasound volumio[1016]: info: Restoring Previous Volume level: 80 false true
May 04 09:57:21 hifi-fasound volumio[1016]: info: VolumeController::SetAlsaVolume100
May 04 09:57:21 hifi-fasound sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:57:21 hifi-fasound volumio[1016]: info: Volume configurations have been set
May 04 09:57:21 hifi-fasound volumio[1016]: [SpotifyConnect] Creating VLS config file
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: [SpotifyConnect] Creating VLS config file
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:21 hifi-fasound sudo[1946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 04 09:57:21 hifi-fasound sudo[1946]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:57:21 hifi-fasound sudo[1943]: pam_unix(sudo:session): session closed for user root
May 04 09:57:21 hifi-fasound systemd[1]: Stopping Music Player Daemon...
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioUpdateVolumeSettings
May 04 09:57:21 hifi-fasound volumio[1016]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreStateMachine::pushState
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioPushState
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 04 09:57:21 hifi-fasound systemd[1]: Starting Music Player Daemon...
May 04 09:57:21 hifi-fasound volumio[1016]: [SpotifyConnect] Restarting Vollibrespot Daemon
May 04 09:57:21 hifi-fasound volumio[1016]: [SpotifyConnect] Restarting Vollibrespot Daemon
May 04 09:57:21 hifi-fasound sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
May 04 09:57:21 hifi-fasound sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:57:21 hifi-fasound systemd[1]: Stopping Volspotconnect2 Daemon...
May 04 09:57:21 hifi-fasound sudo[1976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service
May 04 09:57:21 hifi-fasound systemd[1]: Starting Volspotconnect2 Daemon...
May 04 09:57:21 hifi-fasound systemd[1]: Started Volspotconnect2 Daemon.
May 04 09:57:21 hifi-fasound sudo[1970]: pam_unix(sudo:session): session closed for user root
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreStateMachine::pushState
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioPushState
May 04 09:57:21 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 04 09:57:21 hifi-fasound sudo[1976]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:57:21 hifi-fasound volumio[1979]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 04 09:57:21 hifi-fasound volumio[1979]: Reading Config from "/tmp/volspotify.toml"
May 04 09:57:21 hifi-fasound volumio[1979]: [Vollibrespot] : Using Alsa backend with device: softvolume
May 04 09:57:21 hifi-fasound volumio[1016]: info: MPD Permissions set
May 04 09:57:21 hifi-fasound systemd[1]: Stopping Volspotconnect2 Daemon...
May 04 09:57:21 hifi-fasound systemd[1]: Starting Volspotconnect2 Daemon...
May 04 09:57:21 hifi-fasound systemd[1]: Started Volspotconnect2 Daemon.
May 04 09:57:21 hifi-fasound sudo[1976]: pam_unix(sudo:session): session closed for user root
May 04 09:57:22 hifi-fasound volumio[1988]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 04 09:57:22 hifi-fasound volumio[1988]: Reading Config from "/tmp/volspotify.toml"
May 04 09:57:22 hifi-fasound volumio[1988]: [Vollibrespot] : Using Alsa backend with device: softvolume
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 04 09:57:22 hifi-fasound volumio[1016]: info: Getting Alsa Cards List without I2S DAC
May 04 09:57:22 hifi-fasound volumio[1988]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" }
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 09:57:22 hifi-fasound systemd[1]: Started Music Player Daemon.
May 04 09:57:22 hifi-fasound sudo[1946]: pam_unix(sudo:session): session closed for user root
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: cdplayer , getMPDConfigString
May 04 09:57:22 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 04 09:57:22 hifi-fasound volumio[1016]: [SpotifyConnect] Vollibrespot Daemon service restarted!
May 04 09:57:22 hifi-fasound volumio[1016]: [SpotifyConnect] Vollibrespot Daemon service restarted!
May 04 09:57:22 hifi-fasound volumio[1016]: error: updateQueue error: null
May 04 09:57:22 hifi-fasound volumio[1016]: error: Upnp client error: Error: This socket has been ended by the other party
May 04 09:57:26 hifi-fasound volumio[1016]: info: VolumeController::SetAlsaVolume80
May 04 09:57:26 hifi-fasound volumio[1016]: info: CoreStateMachine::pushState
May 04 09:57:26 hifi-fasound volumio[1016]: info: CoreCommandRouter::volumioPushState
May 04 09:57:26 hifi-fasound volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 04 09:57:28 hifi-fasound volumio[1016]: info: Checking Spotify Web API
May 04 09:57:28 hifi-fasound volumio[1016]: info: Checking Spotify Web API
May 04 09:57:28 hifi-fasound volumio[1016]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443"
May 04 09:57:28 hifi-fasound volumio[1016]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running
May 04 09:57:28 hifi-fasound volumio[1016]: at Socket._healthCheck (dgram.js:638:11)
May 04 09:57:28 hifi-fasound volumio[1016]: at Socket.send (dgram.js:435:8)
May 04 09:57:28 hifi-fasound volumio[1016]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21)
May 04 09:57:28 hifi-fasound volumio[1016]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23)
May 04 09:57:28 hifi-fasound volumio[1016]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2651:18)
May 04 09:57:28 hifi-fasound volumio[1016]: at ontimeout (timers.js:482:11)
May 04 09:57:28 hifi-fasound volumio[1016]: at tryOnTimeout (timers.js:317:5)
May 04 09:57:28 hifi-fasound volumio[1016]: at Timer.listOnTimeout (timers.js:277:5)
May 04 09:57:28 hifi-fasound volumio[1016]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Authenticated as "1opqbvca26fsd4g60igcyctv5" !
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Using alsa sink
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Country: "TW"
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Metadata pipe established
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 }
May 04 09:57:28 hifi-fasound sudo[2027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 09:56
May 04 09:57:28 hifi-fasound sudo[2027]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Fetching autoplay context uri
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714816648697 }
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : SessionActive!
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 119970865213098212147211882846637349857, audio_type: Track } }
May 04 09:57:28 hifi-fasound volumio[1988]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 119970865213098212147211882846637349857, audio_type: Track } }
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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae"
VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021"
VOLUMIO_VERSION="2.917"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"