search

philippe44 / LMS-YouTube

Play YouTube videos on LMS
38 stars 15 forks source link

Plugin stopped working #32

Closed tophee closed 3 years ago

tophee commented 3 years ago

The plugin stopped playing any yt song (but search still works).

I'm unable to post this to the plugin-thread on the squeezebox forum because of character restrictions so I'm sending you my debug log here. Hope it helps. 

[21-04-01 08:18:47.9340] Slim::Player::StreamingController::_setPlayingState (2377) new playing state PAUSED
[21-04-01 08:18:47.9352] Slim::Player::Source::playmode (95) 00:04:20:1f:16:50: Current playmode: pause
[21-04-01 08:18:48.0803] Slim::Player::StreamingController::playerEndOfStream (2286) 00:04:20:1f:16:50
[21-04-01 08:18:55.7090] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:18:55.7095] Slim::Player::StreamingController::_Stop (610) Song queue is now 100
[21-04-01 08:18:55.7097] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:18:55.7097] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:18:55.7099] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:18:55.7150] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:18:55.7151] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:18:55.7155] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:18:55.7164] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:18:55.7166] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:18:55.7169] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:18:55.7175] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:18:55.7181] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:18:55.7198] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:18:56.2074] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:18:56.2076] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:18:56.2086] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:18:56.2088] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:19:08.1248] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:19:08.1253] Slim::Player::StreamingController::_Stop (610) Song queue is now 0
[21-04-01 08:19:08.1255] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:19:08.1255] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:19:08.1257] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:19:08.1259] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:19:08.1264] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:19:08.1275] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:19:08.4697] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:19:08.4699] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:19:08.4708] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:19:08.4710] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:19:59.0151] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:19:59.0152] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:19:59.0157] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:19:59.0163] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:19:59.0164] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:19:59.0165] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:19:59.0167] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:19:59.0303] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:19:59.0312] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:19:59.0327] Slim::Control::Request::execute (1888) Error: While trying to run function coderef [Slim::Control::Commands::playlistJumpCommand]: [Illegal division by zero at /usr/share/perl5/Slim/Player/Source.pm line 47.
]
[21-04-01 08:19:59.0736] Slim::Display::Display::curLines (487) Error: bad lines function: Illegal division by zero at /usr/share/perl5/Slim/Player/Source.pm line 47.
[21-04-01 08:19:59.0739] Slim::Display::Graphics::render (113) Error: bad lines function - non hash based display formats are deprecated
[21-04-01 08:19:59.5312] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:19:59.5314] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:19:59.5322] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:19:59.5325] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:20:16.8004] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:20:16.8006] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:20:16.8010] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:20:16.8016] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:20:16.8016] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:20:16.8018] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:20:16.8020] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:20:16.8157] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:20:16.8166] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:20:16.8180] Slim::Control::Request::execute (1888) Error: While trying to run function coderef [Slim::Control::Commands::playlistJumpCommand]: [Illegal division by zero at /usr/share/perl5/Slim/Player/Source.pm line 47.
]
[21-04-01 08:20:17.1572] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:20:17.1574] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:20:17.1581] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:20:17.1582] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:21:07.5265] main::init (387) Starting Logitech Media Server (v7.9.3, 1597753178, Sun Aug 23 19:21:22 CEST 2020) perl 5.026001 - x86_64-linux-gnu-thread-multi
[21-04-01 08:21:11.8062] Slim::Player::TranscodingHelper::loadConversionTables (49) Loading conversion config files...
[21-04-01 08:21:15.3182] Plugins::CustomScan::Scanner::executeSQLFile (522) Couldn't open: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/CustomScan/SQL/SQLite/dbupgrade_valuesort.sql : No such file or directory
[21-04-01 08:21:15.3185] Plugins::CustomScan::Scanner::executeSQLFile (522) Couldn't open: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/CustomScan/SQL/SQLite/dbupgrade_extravalue.sql : No such file or directory
[21-04-01 08:21:15.3187] Plugins::CustomScan::Scanner::executeSQLFile (522) Couldn't open: /var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/CustomScan/SQL/SQLite/dbupgrade_valuetype.sql : No such file or directory
[21-04-01 08:21:17.0852] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/-hRuzrbhPKE
[21-04-01 08:21:17.0855] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 0
[21-04-01 08:21:17.0861] Slim::Player::Squeezebox::reconnect (88) 00:04:20:22:b9:11 forcing stop on pseudo-reconnect
[21-04-01 08:21:17.1654] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:17.1656] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 0
[21-04-01 08:21:17.1669] Slim::Player::StreamingController::playerActive (2013) 00:04:20:1f:16:50 player already active: 00:04:20:1f:16:50
[21-04-01 08:21:17.1672] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:21:17.1677] Slim::Player::StreamingController::_Stop (610) Song queue is now 0
[21-04-01 08:21:17.1678] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:21:17.1679] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:21:17.1680] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:17.1682] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:21:17.1687] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:17.1690] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:17.1711] Slim::Player::StreamingController::playerActive (2013) 00:04:20:1f:16:50 player already active: 00:04:20:1f:16:50
[21-04-01 08:21:17.1712] Slim::Player::Squeezebox::reconnect (80) 00:04:20:1f:16:50 restaring play on pseudo-reconnect at 0
[21-04-01 08:21:17.1713] Slim::Player::StreamingController::playerReconnect (2092) 00:04:20:1f:16:50
[21-04-01 08:21:17.1721] Slim::Player::StreamingController::_Stop (610) Song queue is now 0
[21-04-01 08:21:17.1722] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:21:17.1723] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:21:17.1725] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:21:17.1728] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:17.1731] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:17.1911] Slim::Player::Boom::setLineInLevel (444) Setting line in level to 50
[21-04-01 08:21:17.2313] Slim::Player::Song::new (107) index 0 -> deezer://30751.dzr
[21-04-01 08:21:17.2317] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 0
[21-04-01 08:21:17.2322] Slim::Player::Squeezebox::reconnect (88) 00:04:20:18:15:57 forcing stop on pseudo-reconnect
[21-04-01 08:21:17.2524] Slim::Player::Song::new (107) index 0 -> file:///c/media/Music/c/Marius%20Muller-Westernhagen/Westernhagen%20Live,%20Disc%201/03%20Marius%20Muller-Westernhagen%20-%20Weist%20du,%20Das%20Ich%20Glucklich%20Bin.flac
[21-04-01 08:21:17.2526] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 0
[21-04-01 08:21:17.2540] Slim::Player::Squeezebox::reconnect (88) 00:04:20:1e:7f:59 forcing stop on pseudo-reconnect
[21-04-01 08:21:17.2557] Slim::Player::Boom::setLineInLevel (444) Setting line in level to 50
[21-04-01 08:21:17.2639] Slim::Player::Song::new (107) index 4 -> youtube://www.youtube.com/v/1ZYbU82GVz4
[21-04-01 08:21:17.2642] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 4
[21-04-01 08:21:17.2648] Slim::Player::Squeezebox::reconnect (88) 00:04:20:27:da:2d forcing stop on pseudo-reconnect
[21-04-01 08:21:18.1126] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:21:18.1128] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/2MiXKO-seWY
[21-04-01 08:21:18.1138] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:21:18.1140] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:21:18.1257] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:21:29.1046] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:21:29.1048] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:21:29.1065] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:21:29.1066] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:21:29.1070] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:21:29.1076] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:21:29.1077] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:21:29.1079] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:21:29.1082] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:21:29.1086] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:21:29.1094] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:21:29.5437] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:21:29.5440] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/JGwWNGJdvx8
[21-04-01 08:21:29.5453] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:21:29.5456] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:22:04.6369] Slim::Player::TranscodingHelper::getConvertCommand2 (445) Matched: flc->flc via: -
[21-04-01 08:22:04.6427] Slim::Player::TranscodingHelper::getConvertCommand2 (442) Error: Didn't find any command matches for type: mp3
[21-04-01 08:22:17.1053] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:22:17.1054] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:22:17.1061] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:22:17.1068] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:22:17.1069] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:22:17.1071] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/VJ2rlci9PE0
[21-04-01 08:22:17.1073] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:22:17.1211] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/VJ2rlci9PE0
[21-04-01 08:22:17.1221] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/VJ2rlci9PE0
[21-04-01 08:22:17.1531] Slim::Control::Request::execute (1888) Error: While trying to run function coderef [Slim::Control::Commands::playlistJumpCommand]: [Illegal division by zero at /usr/share/perl5/Slim/Player/Source.pm line 47.
]
[21-04-01 08:22:17.1923] Slim::Display::Display::curLines (487) Error: bad lines function: Illegal division by zero at /usr/share/perl5/Slim/Player/Source.pm line 47.
[21-04-01 08:22:17.1924] Slim::Display::Graphics::render (113) Error: bad lines function - non hash based display formats are deprecated
[21-04-01 08:22:17.5546] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:22:17.5548] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/VJ2rlci9PE0
[21-04-01 08:22:17.5555] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:22:17.5558] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:22:29.2034] Slim::Player::StreamingController::stop (2117) 00:04:20:1f:16:50
[21-04-01 08:22:29.2036] Slim::Player::StreamingController::resetSongqueue (1796) Song queue is now 
[21-04-01 08:22:29.2041] Slim::Player::StreamingController::play (2120) 00:04:20:1f:16:50
[21-04-01 08:22:29.2047] Slim::Player::StreamingController::_setPlayingState (2377) new playing state STOPPED
[21-04-01 08:22:29.2048] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:22:29.2049] Slim::Player::Song::new (107) index 0 -> youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:22:29.2051] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state TRACKWAIT
[21-04-01 08:22:29.2054] Slim::Player::StreamingController::_playersMessage (796) Getting stream info...: youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:22:29.2062] Slim::Player::Song::getNextSong (228) youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:22:29.5410] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:22:29.5412] Slim::Player::StreamingController::_playersMessage (796) Problem: Can't open file for:: youtube://www.youtube.com/v/_dK2tDK9grQ
[21-04-01 08:22:29.5419] Slim::Player::StreamingController::_setStreamingState (2386) new streaming state IDLE
[21-04-01 08:22:29.5422] Slim::Player::StreamingController::nextsong (889) The next song is number 0, was 0
[21-04-01 08:29:50.3878] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:31:33.8986] Plugins::YouTube::ProtocolHandler::__ANON__ (448) no stream/DASH found
[21-04-01 08:32:11.2359] Slim::Control::Request::execute (1888) Error: While trying to run function coderef [Slim::Control::Commands::playlistJumpCommand]: [Illegal division by zero at /usr/share/perl5/Slim/Player/Source.pm line 47.
]
[21-04-01 08:32:11.6018] Plugins::YouTube::ProtocolHand
superjasiek commented 3 years ago

Same here (448) no stream/DASH found

vstefanoxx commented 3 years ago

Same here. The plugin is correctly configured, I can see my subscriptions and history but playback is not working.

tophee commented 3 years ago

I believe the issue has been resolved. Did you to update to the latest version of both the plugin and LMS? (see the slim devices forum for more on this).

vstefanoxx commented 3 years ago

You are right. The plugin was updated but LMS wasn't: updating it fix everything.