-- Logs begin at Sun 2024-03-31 14:33:01 +07, end at Sun 2024-03-31 16:21:53 +07. -- Mar 31 16:20:07 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 16:20:07 volumio volumio[888]: info: CURURI: playlists Mar 31 16:20:07 volumio volumio[888]: info: Listing playlists Mar 31 16:20:07 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:11 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 31 16:20:11 volumio volumio[888]: info: CURURI: genres:// Mar 31 16:20:11 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:14 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 31 16:20:14 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:16 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 31 16:20:16 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:18 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Mar 31 16:20:18 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:7A2cQg3qm8speSElTwxTzV Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:4RF3cNvUyTDYiuj1Xw4XWs Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:1NM5GQ6KonfH1g4GFwiZBV Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:1FUtxC0Zgnah8bopGn0f1T Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:5o6OYbCkluSsyqmeC0UnBY Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:4dj7PpfQYMcV4SgsfliYd2 Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:55yOevhR4iAUypVIv62SK0 Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:21RT1JDpXXV0Wr6biy3KUL Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:7r6mKPa8JCGGZbKahBD8EM Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:4DYsJGF3u9Y8nEVn1jktL9 Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:4jpctBOgVCM2gjozYLpzW6 Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:13oSykCrgLvc2K2Cy4bM1E Mar 31 16:20:18 volumio volumio[888]: info: Preloading song: spotify:track:32pQP3XD7rIYraHKMVq9pu Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:7A2cQg3qm8speSElTwxTzV in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:7A2cQg3qm8speSElTwxTzV Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:4RF3cNvUyTDYiuj1Xw4XWs in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:4RF3cNvUyTDYiuj1Xw4XWs Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:1NM5GQ6KonfH1g4GFwiZBV in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:1NM5GQ6KonfH1g4GFwiZBV Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:1FUtxC0Zgnah8bopGn0f1T in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:1FUtxC0Zgnah8bopGn0f1T Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:5o6OYbCkluSsyqmeC0UnBY in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:5o6OYbCkluSsyqmeC0UnBY Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:4dj7PpfQYMcV4SgsfliYd2 in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:4dj7PpfQYMcV4SgsfliYd2 Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:55yOevhR4iAUypVIv62SK0 in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:55yOevhR4iAUypVIv62SK0 Mar 31 16:20:18 volumio volumio[888]: info: Exploding uri spotify:track:21RT1JDpXXV0Wr6biy3KUL in service spop Mar 31 16:20:18 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:21RT1JDpXXV0Wr6biy3KUL Mar 31 16:20:19 volumio volumio[888]: info: Exploding uri spotify:track:7r6mKPa8JCGGZbKahBD8EM in service spop Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:7r6mKPa8JCGGZbKahBD8EM Mar 31 16:20:19 volumio volumio[888]: info: Exploding uri spotify:track:4DYsJGF3u9Y8nEVn1jktL9 in service spop Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:4DYsJGF3u9Y8nEVn1jktL9 Mar 31 16:20:19 volumio volumio[888]: info: Exploding uri spotify:track:4jpctBOgVCM2gjozYLpzW6 in service spop Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:4jpctBOgVCM2gjozYLpzW6 Mar 31 16:20:19 volumio volumio[888]: info: Exploding uri spotify:track:13oSykCrgLvc2K2Cy4bM1E in service spop Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:13oSykCrgLvc2K2Cy4bM1E Mar 31 16:20:19 volumio volumio[888]: info: Exploding uri spotify:track:32pQP3XD7rIYraHKMVq9pu in service spop Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: EXPLODING URI:spotify:track:32pQP3XD7rIYraHKMVq9pu Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQAwahdsHQSJHQ-fLDPZEwwK6hvJ5Ivs3I8r_2u27JaW-ov2MoaWRx_gkEKKRfB9h1C8_c7UriOh4BbYaHItitZSa3jNz0oH1Kcy0sHVLzspYmnHYK9AVda8HssACLz1MiWRPc3XR1bg7lCoHbHni-FDAovMcQH28vxG7GIcoTnb-U3nRmD2maW1KXQGjC0WkZJq9_F6fb_t_g0vqPm_v_p3JFxben7VnfN-nt4XRzExTL-VqcgnbCTVXfzHQnNaMf2FYNM Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQB6QQY6KrDSgsqLOXc-Eiy4eaxvXrQKigKZXlyXRclpvVR2cwMEF0ap04Ct2fUkTOLQ7aLhpa1xZSqphg3gE4j4b-LS3PRyFPIBxFDZnPFq-SSlEC9jhYnG03J5xRYFOxJjUAAJM_y7dDblRG2DpKvhOd3KFn48ifWokLBuvwCU0mgObxiYhGJf5GZJfk0D41KzndqA6B8j3Om2hr1u-mCA8YAQ2DT3fwjJLJXPZ6D0N8V4B5uD8V7aaJBTLt8wHS0WoPY Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQBwOJxbUrl8lFz4-pvP5w6pKYJbJUdT98QpgBLjN9Vz7prHoB75-7ucA0n6nW_8oy65YRQh5Xd0qOJ-NYTacyajujJayDBQU-B_l_1oyZYFoUSBhzIfyG17y8XL17yYMdUK37eOK9YCBMWYqkxHjmc6Ys7jX5N24P-iyBGeOdhKoFtLiWmlGOvqHNPRUkNu2TVERBtOjFXn6bc8rV-YYvQWCVU2z4b3DvWNe29ELtO0z5_7qxbvTY6q6wUQtuWqwMX4cys Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQAxtZktkI7QI4IbwbAPzABFXkvB2-AhVGnAwGqjICdLYsRFyhwfKKCZQ1-6czhUFHZPCujwitLgm5KMALKC2L9JeOp8z0b05O8-epAuECtr-voMwIdLDVjcCat9YJV1GUNUJkUSGhPwXIjJaIPRFlD_gATRTH1sFJKpSxpFyfuxUGlOgHHV-9Xes10houVBy1JSYyfp2n0gxQc7eGq1FLpTfaCu7ZXZuHbeIsWiziErDYepYGSxGR8gr6BmLkBYHSRR4Wc Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQDHWPNakVnliiFm0TV23V9OWZagXWMrK04_VFQM7OGhit5GBLofjZ7VrQouTkxaTKRMi4GHZkEesT9_LvCM_b8lpdHNxYb4Cz3dUM8CUBxH4UoQucRRr8_mvSSpw-TOXgnNOcKBRjQLdfY2s25gse8hOHy1724oTnTjT5uNFQQ8V7fKqUVk9pYehfJP0bQ6MCewBt4ASFHO9_jTF7ilQUQLMIzdGsY6ldAkecZANpzg4J7Qk8trPJFrIu9K2kUM3xBhi2E Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQCPy2-Ex4aJ8kyVi3zC5adat3Iu1v94aj1sd6GhP8njpjlrKISbhUdOHoxZSTypWGULXegWDhXRtc_0B2cjUxjAl3CMgAtyMWU5NpjxngjZgWkQlp9tfgy-eAPfp8y2IcUH820Go8USS9O5zS96OxRWvP2XFwFTlZRwvlJpyr_FEoxvC80gFwmwGRRKMiUecpGGWLShxW0BvXZ3ObvZaIipU0pHeAlfSBDxwQ97IQcJgXufxmTiwS72afwiFJtWOj2h2Ec Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7A2cQg3qm8speSElTwxTzV","service":"spop","name":"Mr Crowley","artist":"Billy Watman","album":"Mr Crowley","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b27386640d1ab6d92ee6c1f33877","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQC54_JXSvCdDMgT2vEzEKk4G4pkVCcpyrMCIhLxuXsrFIoUvAY1NeMSAFr4ihiTLLZlQ0xEnsln14XKtD_IBR_tRwIYcABw9Uj6lotbsK4KDPchkfezofHn54a1neS9VnrRfR82uMAve-U2sllw6XMfXjnl6rHNFHIGO-mhTcE6xaIkRibxvRj3C88UU3XjiXGPyWQMH5P765ZiBEObVqEbb-FF8GgxmMjoUXIA15KQSpNPkAn5RjK3IEnbeb-W-e8Tb-I Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQBa6uX-is1KmaxmNicMXtHkfzyn1DL1g3pZ20XWENXRM1dBJohwQwMTjRdtWB1qTGC7hYIrxO1IpOaCioBmU8CM7nM6SqQqaNEiyCdOunrTchrDpzhN-BzQ9h6chSu-Vpp2_SJAu8QPd_Dyj8gXkT3EgNiMtvWtcxuGnESyGpYr5PeDJII11-ed9NDbLKbAAyfySTMIYYAeLhYbWousa9BJ21ZzC0Xr-mFMzMWeFwqsBGvBApV5xLDO9HTCVo3Vz9AsqMk Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1FUtxC0Zgnah8bopGn0f1T","service":"spop","name":"September","artist":"Jon Hart","album":"Annual","type":"song","duration":211,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8e420ffb78da025e1ed0924","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1NM5GQ6KonfH1g4GFwiZBV","service":"spop","name":"Fairytale Of New York","artist":"Billy Watman","album":"Fairytale Of New York","type":"song","duration":240,"albumart":"https://i.scdn.co/image/ab67616d0000b2736f444fbebbc53aef12317351","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4RF3cNvUyTDYiuj1Xw4XWs","service":"spop","name":"Somebody That I Used to Know","artist":"Mike Dawes","album":"What Just Happened?","type":"song","duration":244,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b0ea4396a91831957a08289","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQA_spdlj0FUgocfS274_5ttPlNhzcicobVrfR7gXhhGBpwJ1klQAnM6_9rOANP6-pLQurafab484uBBxrZepNQ9T7VFvoNSsig-tGrxZyePEY8bDTCQr82l27te0SUSdd6HxroN6oFHOFLrOshcICwV2R4PGZeVccuhdipST5ErRBsoAJwHg8PaUe5VB5e9ngwWP2_JHZHVx40ydg1nrDk-20OgTLgRjZrYyC3KK_FU5GSgvV6wYZApSMl8UaTi5CKYgGE Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQAawBhdEle9U5nbShjWuOzjMDaeGmtnW6LhRLSW_GtOGD4EXsDYaIt-UHlGbxeeY25XqeF3O0wzfI3Sf6e2gaXD41kDCXCSSnLGHyf1qNmOG6DlWeN9jUHebTeEhV3JmHutawsBVRZkIWMMGa-UhT9QbdIHZPBOnzYdUY-g4UXWO58s7mfq0vPzL4D7QgG241Rq1GT6NlJ_4lttFV9_AHH0h23cR7FhtBa4ZXpQf2wskaYKIkzVEMI2nm_VdwuLEE020Vw Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5o6OYbCkluSsyqmeC0UnBY","service":"spop","name":"April (Reminiscent Rain)","artist":"Jon Hart","album":"Annual","type":"song","duration":359,"albumart":"https://i.scdn.co/image/ab67616d0000b273d8e420ffb78da025e1ed0924","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4dj7PpfQYMcV4SgsfliYd2","service":"spop","name":"We Are the Champions","artist":"Billy Watman","album":"On With the Show","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273803851f57ce333f4adeada9c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQAMzYo49GzaOcp1ocTWrPHtSh0AZGmOGxWXCpIqdcw-uWGuffuT_nm2s9ZCIALhjq0MarphdIQqqcs8udhbaOaZevaBA8wpRIt3CNqrVtDeGymuBfdNhxewhoVB-fFJzIPwmKqYwDmUCC3RXcE2gWITGJLMpEq_8gn1choP6Y9_AlywnzajHn5TBr3neLJk8VF7IeKmcTsol3gjMoPeoYTOBnbUwGuAP2QJC8TtuIxXxGHw3cs4owei1O5Yfn32rpjfKYM Mar 31 16:20:19 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:55yOevhR4iAUypVIv62SK0","service":"spop","name":"Careless Whisper","artist":"Alexandr Misko","album":"Beyond the Box","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b27342d2437f2266500d5933b5f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQDkJpanxWVqcCn_nJRgcgdZemkZkg3BHFNEcTubO8gyKviy_eUu2LwjsvqqES_aZJLDEHUEfzhMU-sP9JLWYIJPnL3Ypv7MNrdlQ4YX46lqBOmLy-3QC7prxoCDmWjn9awx-ZnatgB9lQOUGo0bsBs78GJfKymLdVlGcBC4xm3iIIflQUqA3jsAFNg6US6w46yHoTLpwYprIqCfVlHyyTKt6iP8y92QD2l3Hl1tpQ02gxF0MjlTIpMPeigKuK-NOr00rmY Mar 31 16:20:19 volumio volumio[888]: info: New access token = BQAkk9fywpuT9DWIOQAvJhmco0ra1ZTifxuXFsHRe-dQlloKU31TH5vkBgxu2OLpkQgN-ghfQh_dJi98IkvD3YVwTT3l9QX6oIx-nslO0RBkny8pokLWdiaX78lm2mcPx1pUPZ9WuKZY_e-__C0qTLM8tG336eq6Z4DIHNiPPgiyrUaYASMYE1rc1aX0I9yLJBZadOowBEQ1G2MnXDo7mF-1x2us9_z1kVPsCC8BxHqOeNQjjV8MxD5bzQzTEGdSHSUlKNM Mar 31 16:20:20 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:21RT1JDpXXV0Wr6biy3KUL","service":"spop","name":"Radio Gaga","artist":"Billy Watman","album":"On With the Show","type":"song","duration":325,"albumart":"https://i.scdn.co/image/ab67616d0000b273803851f57ce333f4adeada9c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:20 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4jpctBOgVCM2gjozYLpzW6","service":"spop","name":"The Show Must Go On","artist":"Billy Watman","album":"On With the Show","type":"song","duration":264,"albumart":"https://i.scdn.co/image/ab67616d0000b273803851f57ce333f4adeada9c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:20 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7r6mKPa8JCGGZbKahBD8EM","service":"spop","name":"Billie Jean","artist":"Alexandr Misko","album":"Beyond the Box","type":"song","duration":235,"albumart":"https://i.scdn.co/image/ab67616d0000b27342d2437f2266500d5933b5f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:20 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:13oSykCrgLvc2K2Cy4bM1E","service":"spop","name":"A Celtic Dream","artist":"Michele McLaughlin","album":"A Celtic Dream","type":"song","duration":103,"albumart":"https://i.scdn.co/image/ab67616d0000b273df1de6a982a0075de9a4ea95","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:20 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:32pQP3XD7rIYraHKMVq9pu","service":"spop","name":"Stormy Weather","artist":"Red Garland Trio","album":"All Kinds Of Weather","type":"song","duration":635,"albumart":"https://i.scdn.co/image/ab67616d0000b273d5134acf998be4a0012a2067","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:20 volumio volumio[888]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4DYsJGF3u9Y8nEVn1jktL9","service":"spop","name":"Englishman in New York","artist":"Sungha Jung","album":"Mixtape","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b273733fc8ba078f58cb8199ea86","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Mar 31 16:20:24 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Mar 31 16:20:24 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:43 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:43 volumio volumio[888]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::ClearQueue Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::stop Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::clearPlayQueue Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::saveQueue Mar 31 16:20:43 volumio volumio[888]: info: CoreCommandRouter::volumioPushQueue Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::addQueueItems Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::addQueueItems Mar 31 16:20:43 volumio volumio[888]: info: Preload queue cleared Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:7A2cQg3qm8speSElTwxTzV Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:7A2cQg3qm8speSElTwxTzV Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:4RF3cNvUyTDYiuj1Xw4XWs Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:4RF3cNvUyTDYiuj1Xw4XWs Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:1NM5GQ6KonfH1g4GFwiZBV Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:1NM5GQ6KonfH1g4GFwiZBV Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:1FUtxC0Zgnah8bopGn0f1T Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:1FUtxC0Zgnah8bopGn0f1T Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:5o6OYbCkluSsyqmeC0UnBY Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:5o6OYbCkluSsyqmeC0UnBY Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:4dj7PpfQYMcV4SgsfliYd2 Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:4dj7PpfQYMcV4SgsfliYd2 Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:55yOevhR4iAUypVIv62SK0 Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:55yOevhR4iAUypVIv62SK0 Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:21RT1JDpXXV0Wr6biy3KUL Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:21RT1JDpXXV0Wr6biy3KUL Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:7r6mKPa8JCGGZbKahBD8EM Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:7r6mKPa8JCGGZbKahBD8EM Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:4DYsJGF3u9Y8nEVn1jktL9 Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:4DYsJGF3u9Y8nEVn1jktL9 Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:4jpctBOgVCM2gjozYLpzW6 Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:4jpctBOgVCM2gjozYLpzW6 Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:13oSykCrgLvc2K2Cy4bM1E Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:13oSykCrgLvc2K2Cy4bM1E Mar 31 16:20:43 volumio volumio[888]: info: Adding Item to queue: spotify:track:32pQP3XD7rIYraHKMVq9pu Mar 31 16:20:43 volumio volumio[888]: info: Using cached record of: spotify:track:32pQP3XD7rIYraHKMVq9pu Mar 31 16:20:43 volumio volumio[888]: info: CoreCommandRouter::volumioPushQueue Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::saveQueue Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::updateTrackBlock Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::getTrackBlock Mar 31 16:20:43 volumio volumio[888]: info: CoreCommandRouter::volumioPlay Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::play index 0 Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::stop Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::play index undefined Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Mar 31 16:20:43 volumio volumio[888]: info: CoreStateMachine::startPlaybackTimer Mar 31 16:20:43 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Mar 31 16:20:43 volumio volumio[888]: info: [1711876843190] ControllerSpotify::clearAddPlayTrack Mar 31 16:20:43 volumio volumio[888]: info: Sending Spotify command with payload to local API: /player/play Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="loading track spotify:track:7A2cQg3qm8speSElTwxTzV (paused: false, position: 0ms)" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=trace msg="emitting websocket event: will_play" Mar 31 16:20:43 volumio volumio[888]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7A2cQg3qm8speSElTwxTzV","play_origin":"go-librespot"}} Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1914" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:7A2cQg3qm8speSElTwxTzV" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="requested aes key for file 7bf47a8c6c9e6fc72923f7221ffcc25790059c37, gid: 7A2cQg3qm8speSElTwxTzV" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="fetched first chunk of 16, total size is 8041172 bytes" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="fetched chunk 3/15, size: 524288" Mar 31 16:20:43 volumio go-librespot[1448]: time="2024-03-31T16:20:43+07:00" level=debug msg="fetched chunk 2/15, size: 524288" Mar 31 16:20:44 volumio go-librespot[1448]: time="2024-03-31T16:20:44+07:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream: ALSA error at snd_pcm_hw_params: Invalid argument" Mar 31 16:20:44 volumio volumio[888]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Mar 31 16:20:44 volumio go-librespot[1448]: time="2024-03-31T16:20:44+07:00" level=debug msg="fetched chunk 1/15, size: 524288" Mar 31 16:20:49 volumio volumio[888]: info: CoreCommandRouter::volumioGetState Mar 31 16:20:49 volumio volumio[888]: info: CorePlayQueue::getTrack 0 Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 16:20:52 volumio volumio[888]: info: Retrieving Cloud Streaming UI Mar 31 16:20:52 volumio volumio[888]: info: Getting Tidal Cloud Configuration Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 16:20:52 volumio volumio[888]: info: Getting Qobuz Cloud Configuration Mar 31 16:20:52 volumio volumio[888]: info: Asking plugin for UI Config Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 16:20:52 volumio volumio[888]: info: Getting Spotify Cloud Configuration Mar 31 16:20:52 volumio volumio[888]: info: Asking plugin for UI Config Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 16:20:52 volumio volumio[888]: info: Saving Spotify Acccount Mar 31 16:20:52 volumio volumio[888]: info: Got Tidal Cloud Configuration Mar 31 16:20:52 volumio volumio[888]: info: Got it Mar 31 16:20:52 volumio volumio[888]: info: Got it Mar 31 16:20:52 volumio volumio[888]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 31 16:20:52 volumio volumio[888]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::volumioGetBrowseSources Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:20:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 31 16:20:56 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:00 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:04 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:08 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:09 volumio volumio[888]: info: Enabling MyMusic plugin bluetooth Mar 31 16:21:09 volumio volumio[888]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Mar 31 16:21:09 volumio volumio[888]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Mar 31 16:21:09 volumio volumio[888]: info: Error: Plugin audio_interface bluetooth not in user plan, not enabling Mar 31 16:21:09 volumio volumio[888]: error: Could not Enable MyMusic Plugin: Error Mar 31 16:21:12 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:16 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:20 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:24 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:28 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:32 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:36 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:40 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:44 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:48 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 31 16:21:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 31 16:21:52 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 31 16:21:52 volumio volumio[888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 16:21:52 volumio volumio[888]: Error: getaddrinfo -3007 Mar 31 16:21:52 volumio volumio[888]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Mar 31 16:21:52 volumio volumio[888]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Mar 31 16:21:52 volumio volumio[888]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Mar 31 16:21:52 volumio volumio[888]: code: -3007, Mar 31 16:21:52 volumio volumio[888]: errno: -3007, Mar 31 16:21:52 volumio volumio[888]: syscall: 'getaddrinfo' Mar 31 16:21:52 volumio volumio[888]: } Mar 31 16:21:52 volumio volumio[888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 31 16:21:53 volumio sudo[14443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-31 16:20 Mar 31 16:21:53 volumio sudo[14443]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"