-- Logs begin at Tue 2019-03-12 18:57:25 UTC, end at Tue 2019-03-12 19:16:47 UTC. --
Mar 12 19:15:38 volumio volumio[839]: info: CorePlayQueue::getTrack 368
Mar 12 19:15:38 volumio volumio[839]: info: CorePlayQueue::getTrack 369
Mar 12 19:15:38 volumio volumio[839]: info: Prefetching next song
Mar 12 19:15:38 volumio volumio[839]: info: DOING PREFETCH IN MPD
Mar 12 19:15:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand add "USB/KINGSTON/montiel medieval 14/The Beatles - Love/08 the beatles - gnik nus.mp3"
Mar 12 19:15:38 volumio volumio[839]: info: sending command...
Mar 12 19:15:38 volumio volumio[839]: info:
Mar 12 19:15:38 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Mar 12 19:15:38 volumio volumio[839]: info: Ignoring MPD Status Update
Mar 12 19:15:38 volumio volumio[839]: info: parsing response...
Mar 12 19:15:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand consume 1
Mar 12 19:15:38 volumio volumio[839]: info: sending command...
Mar 12 19:15:38 volumio volumio[839]: info: ------------------------------ 4ms
Mar 12 19:15:38 volumio volumio[839]: info: parsing response...
Mar 12 19:15:42 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer
Mar 12 19:15:42 volumio volumio[839]: info: CorePlayQueue::getTrack 369
Mar 12 19:15:43 volumio volumio[839]: info:
Mar 12 19:15:43 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Mar 12 19:15:43 volumio volumio[839]: info: Ignoring MPD Status Update
Mar 12 19:15:43 volumio volumio[839]: info:
Mar 12 19:15:43 volumio volumio[839]: ---------------------------- MPD announces state update: player
Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::getState
Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::sendMpdCommand status
Mar 12 19:15:43 volumio volumio[839]: info: sending command...
Mar 12 19:15:43 volumio volumio[839]: info: ------------------------------ 4ms
Mar 12 19:15:43 volumio volumio[839]: info: parsing response...
Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::parseState
Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::sendMpdCommand playlistinfo
Mar 12 19:15:43 volumio volumio[839]: info: sending command...
Mar 12 19:15:43 volumio volumio[839]: info: parsing response...
Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::parseTrackInfo
Mar 12 19:15:43 volumio volumio[839]: info: ControllerMpd::pushState
Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::syncState
Mar 12 19:15:43 volumio volumio[839]: info: CorePlayQueue::getTrack 369
Mar 12 19:15:43 volumio volumio[839]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":55,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Gnik Nus","artist":"The Beatles","album":"Love","uri":"USB/KINGSTON/montiel medieval 14/The Beatles - Love/08 the beatles - gnik nus.mp3","trackType":"mp3"}
Mar 12 19:15:43 volumio volumio[839]: info: CURRENT POSITION 369
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Mar 12 19:15:43 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::pushState
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::getState
Mar 12 19:15:43 volumio volumio[839]: info: CorePlayQueue::getTrack 369
Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::pushState
Mar 12 19:15:43 volumio volumio[839]: info: CoreStateMachine::getState
Mar 12 19:15:43 volumio volumio[839]: info: CorePlayQueue::getTrack 369
Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Mar 12 19:15:43 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 12 19:15:43 volumio sudo[8680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 12 19:15:43 volumio sudo[8680]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 19:15:43 volumio sudo[8680]: pam_unix(sudo:session): session closed for user root
Mar 12 19:15:43 volumio sudo[8685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 12 19:15:43 volumio volumio[839]: info: ------------------------------ 70ms
Mar 12 19:15:43 volumio sudo[8685]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 19:15:43 volumio sudo[8685]: pam_unix(sudo:session): session closed for user root
Mar 12 19:16:00 volumio dhcpd[861]: DHCPDISCOVER from b8:27:eb:3a:f7:81 (volumio) via wlan0
Mar 12 19:16:01 volumio dhcpd[861]: DHCPOFFER on 192.168.211.3 to b8:27:eb:3a:f7:81 (volumio) via wlan0
Mar 12 19:16:33 volumio volumio[839]: info: CorePlayQueue::getTrack 369
Mar 12 19:16:33 volumio volumio[839]: info: CorePlayQueue::getTrack 370
Mar 12 19:16:33 volumio volumio[839]: info: Prefetching next song
Mar 12 19:16:33 volumio volumio[839]: info: DOING PREFETCH IN MPD
Mar 12 19:16:33 volumio volumio[839]: info: ControllerMpd::sendMpdCommand add "USB/KINGSTON/montiel medieval 14/The Beatles - Love/09 the beatles - something.mp3"
Mar 12 19:16:33 volumio volumio[839]: info: sending command...
Mar 12 19:16:33 volumio volumio[839]: info:
Mar 12 19:16:33 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Mar 12 19:16:33 volumio volumio[839]: info: Ignoring MPD Status Update
Mar 12 19:16:33 volumio volumio[839]: info: parsing response...
Mar 12 19:16:33 volumio volumio[839]: info: ControllerMpd::sendMpdCommand consume 1
Mar 12 19:16:33 volumio volumio[839]: info: sending command...
Mar 12 19:16:33 volumio volumio[839]: info: ------------------------------ 4ms
Mar 12 19:16:33 volumio volumio[839]: info: parsing response...
Mar 12 19:16:34 volumio dhcpcd[932]: eth0: carrier acquired
Mar 12 19:16:34 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
Mar 12 19:16:34 volumio dhcpcd[932]: eth0: soliciting an IPv6 router
Mar 12 19:16:34 volumio dhcpcd[932]: eth0: using static address 192.168.2.93
Mar 12 19:16:34 volumio dhcpcd[932]: eth0: adding host route to 192.168.2.93 via 127.0.0.1
Mar 12 19:16:34 volumio dhcpcd[932]: eth0: adding route to 192.168.2.0/24
Mar 12 19:16:34 volumio dhcpcd[932]: eth0: adding default route via 192.168.2.9
Mar 12 19:16:34 volumio avahi-daemon[602]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.2.93.
Mar 12 19:16:34 volumio avahi-daemon[602]: New relevant interface eth0.IPv4 for mDNS.
Mar 12 19:16:34 volumio avahi-daemon[602]: Registering new address record for 192.168.2.93 on eth0.IPv4.
Mar 12 19:16:35 volumio ifplugd(eth0)[635]: Link beat detected.
Mar 12 19:16:35 volumio ifplugd(eth0)[635]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Mar 12 19:16:35 volumio ifplugd(eth0)[635]: client: /sbin/ifup: interface eth0 already configured
Mar 12 19:16:35 volumio ifplugd(eth0)[635]: Program executed successfully.
Mar 12 19:16:36 volumio ntpd[659]: Listen normally on 5 eth0 192.168.2.93 UDP 123
Mar 12 19:16:36 volumio ntpd[659]: peers refreshed
Mar 12 19:16:37 volumio volumio[839]: info: CoreStateMachine::startPlaybackTimer
Mar 12 19:16:37 volumio volumio[839]: info: CorePlayQueue::getTrack 370
Mar 12 19:16:38 volumio volumio[839]: info:
Mar 12 19:16:38 volumio volumio[839]: ---------------------------- MPD announces system playlist update
Mar 12 19:16:38 volumio volumio[839]: info: Ignoring MPD Status Update
Mar 12 19:16:38 volumio volumio[839]: info:
Mar 12 19:16:38 volumio volumio[839]: ---------------------------- MPD announces state update: player
Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::getState
Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand status
Mar 12 19:16:38 volumio volumio[839]: info: sending command...
Mar 12 19:16:38 volumio volumio[839]: info: ------------------------------ 4ms
Mar 12 19:16:38 volumio volumio[839]: info: parsing response...
Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::parseState
Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::sendMpdCommand playlistinfo
Mar 12 19:16:38 volumio volumio[839]: info: sending command...
Mar 12 19:16:38 volumio volumio[839]: info: parsing response...
Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::parseTrackInfo
Mar 12 19:16:38 volumio volumio[839]: info: ControllerMpd::pushState
Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::servicePushState
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::syncState
Mar 12 19:16:38 volumio volumio[839]: info: CorePlayQueue::getTrack 370
Mar 12 19:16:38 volumio volumio[839]: info: STATE SERVICE {"status":"play","position":0,"seek":81,"duration":210,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Something","artist":"The Beatles","album":"Love","uri":"USB/KINGSTON/montiel medieval 14/The Beatles - Love/09 the beatles - something.mp3","trackType":"mp3"}
Mar 12 19:16:38 volumio volumio[839]: info: CURRENT POSITION 370
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::syncState stateService play
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::syncState currentStatus play
Mar 12 19:16:38 volumio volumio[839]: info: Received an update from plugin. extracting info from payload
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::pushState
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::getState
Mar 12 19:16:38 volumio volumio[839]: info: CorePlayQueue::getTrack 370
Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 12 19:16:38 volumio sudo[9118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::pushState
Mar 12 19:16:38 volumio volumio[839]: info: CoreStateMachine::getState
Mar 12 19:16:38 volumio volumio[839]: info: CorePlayQueue::getTrack 370
Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::volumioPushState
Mar 12 19:16:38 volumio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 12 19:16:38 volumio sudo[9118]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 19:16:38 volumio sudo[9118]: pam_unix(sudo:session): session closed for user root
Mar 12 19:16:38 volumio volumio[839]: info: ------------------------------ 71ms
Mar 12 19:16:38 volumio sudo[9126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 12 19:16:38 volumio sudo[9126]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 19:16:38 volumio sudo[9126]: pam_unix(sudo:session): session closed for user root
Mar 12 19:16:40 volumio volumio[839]: Discovery: this is already registered, ea265773-36ac-4a46-a7de-87b84549b0b2
Mar 12 19:16:40 volumio volumio[839]: info: mDNS: Found device Volumio
Mar 12 19:16:40 volumio sudo[9150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 12 19:16:40 volumio sudo[9150]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 12 19:16:40 volumio sudo[9150]: pam_unix(sudo:session): session closed for user root
Mar 12 19:16:43 volumio ntpd_intres[747]: DNS 0.debian.pool.ntp.org -> 194.0.5.123
Mar 12 19:16:46 volumio volumio[839]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 12 19:16:46 volumio volumio[839]: TypeError: Cannot create property 'type' on string 'invalid server key'
Mar 12 19:16:46 volumio volumio[839]: at WebSocket.onError (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:450:16)
Mar 12 19:16:46 volumio volumio[839]: at emitOne (events.js:116:13)
Mar 12 19:16:46 volumio volumio[839]: at WebSocket.emit (events.js:211:7)
Mar 12 19:16:46 volumio volumio[839]: at ClientRequest.upgrade (/volumio/node_modules/engine.io-client/node_modules/ws/lib/WebSocket.js:738:12)
Mar 12 19:16:46 volumio volumio[839]: at Object.onceWrapper (events.js:319:30)
Mar 12 19:16:46 volumio volumio[839]: at emitThree (events.js:136:13)
Mar 12 19:16:46 volumio volumio[839]: at ClientRequest.emit (events.js:217:7)
Mar 12 19:16:46 volumio volumio[839]: at Socket.socketOnData (_http_client.js:472:11)
Mar 12 19:16:46 volumio volumio[839]: at emitOne (events.js:116:13)
Mar 12 19:16:46 volumio volumio[839]: at Socket.emit (events.js:211:7)
Mar 12 19:16:46 volumio volumio[839]: at addChunk (_stream_readable.js:263:12)
Mar 12 19:16:46 volumio volumio[839]: at readableAddChunk (_stream_readable.js:250:11)
Mar 12 19:16:46 volumio volumio[839]: at Socket.Readable.push (_stream_readable.js:208:10)
Mar 12 19:16:46 volumio volumio[839]: at TCP.onread (net.js:607:20)
Mar 12 19:16:46 volumio volumio[839]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 12 19:16:47 volumio sudo[9209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2019-03-12 19:15
Mar 12 19:16:47 volumio sudo[9209]: 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="f1b59d96a3893a42f8609371956ecba0438017d7"
VOLUMIO_FE_VERSION="95791212f747b95a2cb050c16459a831bfe5c7e7"
VOLUMIO_BE_VERSION="1c3c01aeeeb4cff8e6c9a4b6c34e1a558d1b237b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Mar 12 19:57:25 CET 2019"
VOLUMIO_VERSION="2.565"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="64b803aae2d00ba86d00d612c4abb38d"