-- Logs begin at Wed 2022-10-26 11:42:45 UTC, end at Fri 2024-11-01 04:04:22 UTC. -- Nov 01 04:03:55 opera systemd[1]: Time has been changed Nov 01 04:03:56 opera volumio[977]: info: ------------------------------------------- Nov 01 04:03:56 opera volumio[977]: info: ----- Volumio2 ---- Nov 01 04:03:56 opera volumio[977]: info: ------------------------------------------- Nov 01 04:03:56 opera volumio[977]: info: ----- System startup ---- Nov 01 04:03:56 opera volumio[977]: info: ------------------------------------------- Nov 01 04:03:56 opera volumio[977]: info: Found new core plugin music_service/spop. Adding it Nov 01 04:03:56 opera volumio[977]: info: Found new core plugin music_service/dirble. Adding it Nov 01 04:03:56 opera volumio[977]: info: Configured plugin music_service/spop cannot be loaded. Removing from configuration Nov 01 04:03:56 opera volumio[977]: info: Configured plugin music_service/dirble cannot be loaded. Removing from configuration Nov 01 04:03:56 opera volumio[977]: info: Plugin folders cleanup Nov 01 04:03:56 opera volumio[977]: info: Scanning into folder /volumio/app/plugins/ Nov 01 04:03:56 opera volumio[977]: info: Scanning category audio_interface Nov 01 04:03:56 opera volumio[977]: info: Scanning category miscellanea Nov 01 04:03:56 opera volumio[977]: info: Scanning category music_service Nov 01 04:03:56 opera volumio[977]: info: Scanning category plugins.json Nov 01 04:03:56 opera volumio[977]: info: Scanning category system_controller Nov 01 04:03:56 opera volumio[977]: info: Scanning category user_interface Nov 01 04:03:56 opera volumio[977]: info: Scanning into folder /data/plugins/ Nov 01 04:03:56 opera volumio[977]: info: Plugin folders cleanup completed Nov 01 04:03:56 opera volumio[977]: info: Loading plugins from folder /volumio/app/plugins/ Nov 01 04:03:56 opera volumio[977]: info: Loading plugins from folder /data/plugins/ Nov 01 04:03:56 opera volumio[977]: info: Loading plugin "system"... Nov 01 04:03:56 opera volumio[977]: info: Loading plugin "appearance"... Nov 01 04:03:58 opera volumio[977]: info: Applying required configuration parameters for plugin appearance Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "network"... Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "services"... Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "alsa_controller"... Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "upnp"... Nov 01 04:03:58 opera volumio[977]: info: [1730433838551] Starting Upmpd Daemon Nov 01 04:03:58 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "mpd"... Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "networkfs"... Nov 01 04:03:58 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 04:03:58 opera volumio[977]: info: Plugin alarm-clock is not enabled Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "airplay_emulation"... Nov 01 04:03:58 opera volumio[977]: info: [1730433838853] Starting Shairport Sync Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "cd_controller"... Nov 01 04:03:58 opera volumio[977]: error: ATTENTION!!!: Plugin cd_controller does not return adequate promise from onVolumioStart: please update! Nov 01 04:03:58 opera volumio[977]: info: Loading plugin "webradio"... Nov 01 04:03:59 opera volumio[977]: info: Loading plugin "i2s_dacs"... Nov 01 04:03:59 opera volumio[977]: info: I2S DAC not set, start Auto-detection Nov 01 04:03:59 opera volumio[977]: info: Loading plugin "volumiodiscovery"... Nov 01 04:03:59 opera volumio[977]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 04:03:59 opera node[977]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 01 04:03:59 opera node[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 04:03:59 opera node[977]: *** WARNING *** For more information see Nov 01 04:03:59 opera node[977]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 04:03:59 opera node[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 04:03:59 opera node[977]: *** WARNING *** For more information see Nov 01 04:03:59 opera volumio[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 04:03:59 opera volumio[977]: *** WARNING *** For more information see Nov 01 04:03:59 opera volumio[977]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 01 04:03:59 opera volumio[977]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 01 04:03:59 opera volumio[977]: *** WARNING *** For more information see Nov 01 04:03:59 opera volumio[977]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 01 04:03:59 opera volumio[977]: Discovery: StartAdv! undefined Nov 01 04:03:59 opera volumio[977]: Discovery: Started advertising... Opera - undefined Nov 01 04:03:59 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 01 04:03:59 opera volumio[977]: info: Loading plugin "albumart"... Nov 01 04:03:59 opera volumio[977]: info: Plugin example_plugin is not enabled Nov 01 04:03:59 opera volumio[977]: info: Loading plugin "updater_comm"... Nov 01 04:04:00 opera volumio[977]: info: Plugin mpdemulation is not enabled Nov 01 04:04:00 opera volumio[977]: info: Loading plugin "websocket"... Nov 01 04:04:00 opera volumio[977]: info: ___________ START PLUGINS ___________ Nov 01 04:04:00 opera volumio[977]: info: PLUGIN START: appearance Nov 01 04:04:00 opera volumio[977]: info: PLUGIN START: webradio Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 04:04:00 opera volumio[977]: info: [1730433840107] CoreMusicLibrary::Adding element Webradio Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 04:04:00 opera volumio[977]: info: Loading i18n strings for locale zh Nov 01 04:04:00 opera volumio[977]: Updating browse sources language Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::initPlayerControls Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: BOOT COMPLETED Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::Close All Modals sent Nov 01 04:04:00 opera volumio[977]: Express server listening on port 3000 Nov 01 04:04:00 opera volumio[977]: Volumio Calling Home Nov 01 04:04:00 opera volumio[977]: info: CoreStateMachine::resetVolumioState Nov 01 04:04:00 opera volumio[977]: info: CoreStateMachine::getcurrentVolume Nov 01 04:04:00 opera volumio[977]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 04:04:00 opera volumio[977]: info: CoreStateMachine::updateTrackBlock Nov 01 04:04:00 opera volumio[977]: info: CorePlayQueue::getTrackBlock Nov 01 04:04:00 opera volumio[977]: info: Setting Device type: Raspberry PI Nov 01 04:04:00 opera volumio[977]: info: MPD running with PID708 ,establishing connection Nov 01 04:04:00 opera sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 01 04:04:00 opera sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 04:04:00 opera sudo[1062]: pam_unix(sudo:session): session closed for user root Nov 01 04:04:01 opera volumio[977]: info: Reloading queue from file Nov 01 04:04:01 opera volumio[977]: info: VolumeController:: Volume=undefined Mute =undefined Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::pushState Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::getState Nov 01 04:04:01 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:01 opera volumio[977]: info: CoreCommandRouter::volumioPushState Nov 01 04:04:01 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 04:04:01 opera volumio[977]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Nov 01 04:04:01 opera volumio[977]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::setRepeat null single undefined Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::pushState Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::getState Nov 01 04:04:01 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:01 opera volumio[977]: info: CoreCommandRouter::volumioPushState Nov 01 04:04:01 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::setRandom null Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::pushState Nov 01 04:04:01 opera volumio[977]: info: CoreStateMachine::getState Nov 01 04:04:01 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:01 opera volumio[977]: info: CoreCommandRouter::volumioPushState Nov 01 04:04:01 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 04:04:01 opera volumio[977]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1751705","favourite":false} Nov 01 04:04:01 opera volumio[977]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1751705","favourite":false} Nov 01 04:04:01 opera volumio[977]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1751705","favourite":false} Nov 01 04:04:01 opera sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Nov 01 04:04:01 opera sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 04:04:01 opera systemd[1]: Starting ShairportSync AirTunes receiver... Nov 01 04:04:01 opera systemd[1]: Started ShairportSync AirTunes receiver. Nov 01 04:04:01 opera sudo[1073]: pam_unix(sudo:session): session closed for user root Nov 01 04:04:01 opera shairport-sync[1078]: Successful Startup Nov 01 04:04:01 opera volumio[977]: Discovery: adding 44f4c5c9-9d43-4743-898f-7c792aedd13c Nov 01 04:04:01 opera volumio[977]: info: mDNS: Found device Opera Nov 01 04:04:01 opera volumio[977]: info: [1730433841651] Shairport-Sync Started Nov 01 04:04:01 opera volumio[977]: Error adding Membership: Error: addMembership EINVAL Nov 01 04:04:01 opera volumio-remote-updater[674]: [2024-11-01 04:04:01] [connect] Successful connection Nov 01 04:04:01 opera volumio-remote-updater[674]: [2024-11-01 04:04:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730433841 101 Nov 01 04:04:02 opera volumio[977]: info: CoreCommandRouter::volumioGetState Nov 01 04:04:02 opera volumio[977]: info: CoreStateMachine::getState Nov 01 04:04:02 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:02 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 01 04:04:02 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 04:04:02 opera volumio[977]: Plugin multiroom or method getMultiroom not found Nov 01 04:04:02 opera volumio[977]: info: Listing playlists Nov 01 04:04:03 opera volumio[977]: info: VolumeController::SetAlsaVolume0 Nov 01 04:04:03 opera volumio[977]: null Nov 01 04:04:03 opera volumio[977]: info: VolumeController::Volume 0 Nov 01 04:04:03 opera volumio[977]: info: CoreStateMachine::pushState Nov 01 04:04:03 opera volumio[977]: info: CoreStateMachine::getState Nov 01 04:04:03 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:03 opera volumio[977]: info: CoreCommandRouter::volumioPushState Nov 01 04:04:03 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 04:04:03 opera volumio[977]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1751705","favourite":false} Nov 01 04:04:05 opera volumio[977]: info: CoreCommandRouter::Close All Modals sent Nov 01 04:04:06 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 01 04:04:06 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 01 04:04:08 opera sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 01 04:04:08 opera sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 04:04:08 opera systemd[1]: Starting UPnP Renderer front-end to MPD... Nov 01 04:04:08 opera systemd[1]: Started UPnP Renderer front-end to MPD. Nov 01 04:04:08 opera sudo[1098]: pam_unix(sudo:session): session closed for user root Nov 01 04:04:08 opera volumio[977]: info: Upmpdcli Daemon Started Nov 01 04:04:11 opera volumio[977]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=opera&version=2.114&uuid=279d546c3a5ecbbfcb0badfa270577bb" http://updates.volumio.org:7070/downloader-v1/track-device Nov 01 04:04:11 opera volumio[977]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 01 04:04:11 opera volumio[977]: Dload Upload Total Spent Left Speed Nov 01 04:04:11 opera volumio[977]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Nov 01 04:04:11 opera volumio[977]: retrying in 5 seconds, trial 0 Nov 01 04:04:11 opera volumio[977]: Volumio Calling Home Nov 01 04:04:14 opera volumio[977]: info: VolumeController::SetAlsaVolume82 Nov 01 04:04:14 opera volumio[977]: null Nov 01 04:04:14 opera volumio[977]: info: VolumeController::Volume 82 Nov 01 04:04:14 opera volumio[977]: info: CoreStateMachine::pushState Nov 01 04:04:14 opera volumio[977]: info: CoreStateMachine::getState Nov 01 04:04:14 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:14 opera volumio[977]: info: CoreCommandRouter::volumioPushState Nov 01 04:04:14 opera volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 04:04:14 opera volumio[977]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1751705","favourite":false} Nov 01 04:04:20 opera udisks-glue[671]: error: Timeout Nov 01 04:04:21 opera volumio[977]: info: CoreCommandRouter::volumioSeek Nov 01 04:04:21 opera volumio[977]: info: CoreStateMachine::seek Nov 01 04:04:21 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:21 opera volumio[977]: info: TRACKBLOCK {"uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1751705","title":"Radio Taraf ROMANIA - www.RadioTaraf.ro","service":"webradio","name":"Radio Taraf ROMANIA - www.RadioTaraf.ro","albumart":"/albumart","samplerate":"","bitdepth":"","channels":0,"trackType":"webradio"} Nov 01 04:04:21 opera volumio[977]: info: CoreStateMachine::startPlaybackTimer Nov 01 04:04:21 opera volumio[977]: info: CorePlayQueue::getTrack 0 Nov 01 04:04:21 opera volumio[977]: info: [1730433861501] ControllerWebradio::seek Nov 01 04:04:21 opera volumio[977]: info: ControllerMpd::seek Nov 01 04:04:21 opera volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 04:04:21 opera volumio[977]: { AssertionError: false == true Nov 01 04:04:21 opera volumio[977]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Nov 01 04:04:21 opera volumio[977]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2722:24) Nov 01 04:04:21 opera volumio[977]: at ControllerWebradio.seek (/volumio/app/plugins/music_service/webradio/index.js:506:27) Nov 01 04:04:21 opera volumio[977]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1179:15) Nov 01 04:04:21 opera volumio[977]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1303:27) Nov 01 04:04:21 opera volumio[977]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) Nov 01 04:04:21 opera volumio[977]: at emitTwo (events.js:106:13) Nov 01 04:04:21 opera volumio[977]: at Socket.emit (events.js:191:7) Nov 01 04:04:21 opera volumio[977]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Nov 01 04:04:21 opera volumio[977]: at _combinedTickCallback (internal/process/next_tick.js:73:7) Nov 01 04:04:21 opera volumio[977]: name: 'AssertionError', Nov 01 04:04:21 opera volumio[977]: actual: false, Nov 01 04:04:21 opera volumio[977]: expected: true, Nov 01 04:04:21 opera volumio[977]: operator: '==', Nov 01 04:04:21 opera volumio[977]: message: 'false == true', Nov 01 04:04:21 opera volumio[977]: generatedMessage: true } Nov 01 04:04:21 opera volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 04:04:22 opera sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-01 04:03 Nov 01 04:04:22 opera sudo[1121]: 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="7bac1c7b678b8c4c610bba4d70a5c16ee62b7e19" VOLUMIO_FE_VERSION="2d80816ad99ca4dfec2998adad5179fd25e874d5" VOLUMIO_BE_VERSION="9ac13199e84bd2c5cf8082565cccc6b80b02df97" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Apr 9 12:10:35 CEST 2018" VOLUMIO_VERSION="2.114" VOLUMIO_HARDWARE="pi"