When the bot joins the Discord channel, after sending a Play command, it joins the channel in a muted state.
Bot joins voice channel, as expected, but is client-side muted. It appears to attempt to play the first few seconds of songs which were located by the query and exits the channel after some time without playing music.
03:17:04.830 [Init] Loading configuration from /app/.env
03:17:04.837 [Init] Configuration initialized with 28 values
03:17:04.838 [Init] Loading bot config from /app/config.fds
03:17:04.853 [Info] Log saving started. Writing logs to: logs/plex-bot-2026-05-13.log
03:17:04.853 [Init] Starting Plex Music Bot
03:17:04.882 [Init] Registering services
03:17:04.924 [Info] Found 0 extension directories in /source/Extensions
03:17:04.924 [Info] Discovered 0 extensions total
03:17:04.924 [Init] Services registered
03:17:04.955 [Init] Host built, starting services
03:17:04.980 [Debug] Button registered: vol_up with flags VisualPlayer and priority 10
03:17:04.980 [Debug] Button registered: pause_resume with flags VisualPlayer and priority 20
03:17:04.980 [Debug] Button registered: skip with flags VisualPlayer and priority 30
03:17:04.980 [Debug] Button registered: repeat with flags VisualPlayer and priority 40
03:17:04.980 [Debug] Button registered: queue_options with flags VisualPlayer and priority 50
03:17:04.980 [Debug] Button registered: vol_down with flags VisualPlayer and priority 60
03:17:04.980 [Debug] Button registered: radio with flags VisualPlayer and priority 65
03:17:04.980 [Debug] Button registered: similar with flags VisualPlayer and priority 66
03:17:04.981 [Debug] Button registered: adventure with flags VisualPlayer and priority 67
03:17:04.981 [Debug] Button registered: kill with flags VisualPlayer and priority 70
03:17:04.981 [Debug] Button registered: view_queue with flags QueueOptions and priority 10
03:17:04.981 [Debug] Button registered: shuffle_queue with flags QueueOptions and priority 20
03:17:04.981 [Debug] Button registered: clear_queue with flags QueueOptions and priority 30
03:17:05.005 [Init] Starting bot service
03:17:05.005 [Init] Discord event handlers initialized
03:17:05.005 [Init] Lavalink services initialized
03:17:05.007 [Info] Successfully initialized 0 of 0 extensions
03:17:05.007 [Info] Initialized 0 extensions
03:17:05.010 [Init] PlexApiService initialized with server URL: http://192.168.11.17:32400
03:17:05.011 [Info] Music provider registered: Plex (plex)
03:17:05.011 [Init] Registered 1 music providers
03:17:05.011 [Init] Connecting to Discord
03:17:05.014 [Info] [Discord] Discord: Discord.Net v3.19.0 (API v10)
03:17:05.017 [Init] Bot service started
03:17:05.018 [Info] [Discord] Gateway: Connecting
03:17:05.019 [Init] Initializing static player channel (0)...
03:17:05.019 [Debug] [Discord] Gateway: Connecting ApiClient
03:17:05.330 [Debug] [Discord] Rest: GET gateway/bot: 290.13 ms
03:17:05.646 [Debug] [Discord] Gateway: Received Hello
03:17:05.648 [Debug] [Discord] Gateway: Heartbeat Started
03:17:05.659 [Debug] [Discord] Gateway: Identifying
03:17:05.661 [Debug] [Discord] Gateway: Sent Heartbeat
03:17:05.668 [Debug] [Discord] Gateway: Sent Identify
03:17:05.750 [Debug] [Discord] Gateway: Received HeartbeatAck
03:17:05.751 [Debug] [Discord] Gateway: Latency = 100 ms
03:17:05.921 [Debug] [Discord] Gateway: Received Dispatch (READY)
03:17:05.945 [Debug] [Discord] Gateway: GuildDownloader Started
03:17:05.946 [Info] [Discord] Gateway: Connected
03:17:05.946 [Debug] [Discord] Gateway: Raising Event
03:17:05.981 [Debug] [Discord] Gateway: Received Dispatch (GUILD_AVAILABLE)
03:17:05.989 [Debug] [Discord] Gateway: Connected to [REDACTED]
03:17:06.445 [Debug] [Discord] Gateway: GuildDownloader Stopped
03:17:06.494 [Debug] [Discord] App Commands: Successfully built 2 interaction modules.
03:17:06.504 [Info] Discovered 2 interaction modules
03:17:06.504 [Info] Module: MusicInteractionHandler, Commands: 0
03:17:06.504 [Info] Module: MusicCommands, Commands: 5
03:17:06.504 [Info] Command: search
03:17:06.504 [Info] Command: playlist
03:17:06.504 [Info] Command: play
03:17:06.504 [Info] Command: ping
03:17:06.504 [Info] Command: help
03:17:06.775 [Debug] [Discord] Rest: PUT applications/[REDACTED]/commands: 247.45 ms
03:17:06.788 [Info] Registered commands globally
03:17:06.794 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:17:06.795 [Debug] Event bus: subscribed to 'track.started'
03:17:06.795 [Debug] Event bus: subscribed to 'track.ended'
03:17:06.795 [Debug] Event bus: subscribed to 'player.destroyed'
03:17:06.795 [Init] Rich presence enabled — bot status will show now-playing track
03:17:06.796 [Init] Bot is ready. Connected to 1 guilds
03:17:06.797 [Info] [Discord] Gateway: Ready
03:17:07.019 [Warning] Static player channel with ID 0 not found or is not a text channel
03:17:30.943 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:17:30.962 [Debug] Interaction received: type=ApplicationCommand, elapsed=3ms since creation
03:17:30.969 [Debug] [Discord] App Commands: Executing Slash Command: "play" for [REDACTED] in [REDACTED]/general
03:17:31.178 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_CREATE)
03:17:31.189 [Debug] [Discord] Rest: POST interactions/1503959352443404480/aW50ZXJhY3Rpb246MTUwMzk1OTM1MjQ0MzQwNDQ4MDpuTVE1Y21zV2FqSUhEbWlIc0RjTEdWaExwREFvOGlONzhvQkpoRFRrRWZpSlZaQlBaS3dpSDFlOTVYb01Vd05jc2t5OWV1aHNzZ3pYWlFtaDloM2tiOVdxMkxTbjR3RFRuUnB0UGR4MUpnd1Fva2dxMzFmWENpUU5HbzUwRFB6Tw/callback: 209.79 ms
03:17:31.190 [Info] Play command: aesop rock
03:17:31.191 [Info] Searching Plex library for: aesop rock
03:17:31.192 [Debug] Performing Plex API request to: http://192.168.11.17:32400/hubs/search?query=aesop+rock&limit=100&X-Plex-Token=[REDACTED]
03:17:31.193 [Debug] [PlexAPI] GET request to http://192.168.11.17:32400/hubs/search?query=aesop+rock&limit=100&X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:31.289 [Debug] [PlexAPI] Request successful
03:17:31.289 [Debug] Received Plex API response (18826 bytes)
03:17:31.291 [Debug] Processing hub: Artists (artist)
03:17:31.292 [Debug] Processing hub: Shows (show)
03:17:31.292 [Debug] Processing hub: Albums (album)
03:17:31.293 [Debug] Processing hub: Episodes (episode)
03:17:31.293 [Debug] Processing hub: Movies (movie)
03:17:31.293 [Debug] Processing hub: Tracks (track)
03:17:31.293 [Debug] Processing hub: Photo Albums (photoalbum)
03:17:31.293 [Debug] Processing hub: Automatic Tags (autotag)
03:17:31.293 [Debug] Processing hub: Photos (photo)
03:17:31.293 [Debug] Processing hub: Tags (tag)
03:17:31.293 [Debug] Processing hub: Actors (actor)
03:17:31.293 [Debug] Processing hub: Directors (director)
03:17:31.293 [Debug] Processing hub: Genres (genre)
03:17:31.293 [Debug] Processing hub: Collections (collection)
03:17:31.293 [Debug] Processing hub: Playlists (playlist)
03:17:31.293 [Debug] Processing hub: Shared (shared)
03:17:31.293 [Debug] Processing hub: Places (place)
03:17:31.293 [Debug] Parsed search results: 1 artists, 3 albums, 0 tracks, 0 playlists
03:17:31.293 [Info] Search complete. Found 1 artists, 3 albums, 0 tracks, 0 playlists
03:17:31.295 [Debug] Getting tracks for container: /library/metadata/63158/children
03:17:31.295 [Debug] Performing Plex API request to: http://192.168.11.17:32400/library/metadata/63158/children?X-Plex-Token=[REDACTED]
03:17:31.295 [Debug] [PlexAPI] GET request to http://192.168.11.17:32400/library/metadata/63158/children?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:31.308 [Debug] [PlexAPI] Request successful
03:17:31.308 [Debug] Received Plex API response (30061 bytes)
03:17:31.313 [Debug] Retrieved 18 tracks
03:17:31.322 [Debug] [Discord] Gateway: Sent VoiceStateUpdate
03:17:31.413 [Debug] [Discord] Gateway: Received Dispatch (VOICE_STATE_UPDATE)
03:17:31.493 [Debug] [Discord] Gateway: Received Dispatch (VOICE_SERVER_UPDATE)
03:17:32.008 [Debug] Player tracked state change for guild 237711887834415106: Tracked
03:17:32.298 [Debug] [Discord] Rest: GET webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk1OTM1MjQ0MzQwNDQ4MDpuTVE1Y21zV2FqSUhEbWlIc0RjTEdWaExwREFvOGlONzhvQkpoRFRrRWZpSlZaQlBaS3dpSDFlOTVYb01Vd05jc2t5OWV1aHNzZ3pYWlFtaDloM2tiOVdxMkxTbjR3RFRuUnB0UGR4MUpnd1Fva2dxMzFmWENpUU5HbzUwRFB6Tw/messages/@original: 271.93 ms
03:17:32.312 [Debug] Adding 18 tracks to queue
03:17:32.406 [Debug] Playing first track: Secret Knock by Aesop Rock
03:17:32.424 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:17:32.426 [Debug] Player active event for guild 237711887834415106
03:17:32.428 [Info] Progress bar size: medium (16 segments)
03:17:32.429 [Info] Progress bar: Using unicode fallback (no custom emoji IDs configured)
03:17:32.431 [Debug] ImageBuilder initialization started
03:17:32.431 [Debug] HttpClientWrapper initialized successfully
03:17:32.431 [Debug] Attempting to find usable fonts...
03:17:32.440 [Debug] Successfully loaded font: /usr/share/fonts/truetype/dejavu/DejaVuSans.ttf
03:17:32.440 [Debug] Successfully loaded font: /usr/share/fonts/truetype/noto/NotoSans-Regular.ttf
03:17:32.440 [Warning] Failed to load font /usr/share/fonts/opentype/noto/NotoSansCJK-Regular.ttc: Table 'name' is missing
03:17:32.440 [Debug] Successfully loaded font: /usr/share/fonts/truetype/liberation/LiberationSans-Regular.ttf
03:17:32.441 [Debug] Successfully loaded font: /usr/share/fonts/opentype/ipafont-gothic/ipag.ttf
03:17:32.441 [Debug] Successfully loaded font: /usr/share/fonts/opentype/ipafont-mincho/ipam.ttf
03:17:32.444 [Debug] Selected CJK-compatible font: Noto Sans
03:17:32.444 [Info] ImageBuilder initialized with 5 fonts: DejaVuSans.ttf, NotoSans-Regular.ttf, LiberationSans-Regular.ttf, ipag.ttf, ipam.ttf
03:17:32.446 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:32.457 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:17:32.751 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:17:32.782 [Debug] [Discord] Rest: PATCH webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk1OTM1MjQ0MzQwNDQ4MDpuTVE1Y21zV2FqSUhEbWlIc0RjTEdWaExwREFvOGlONzhvQkpoRFRrRWZpSlZaQlBaS3dpSDFlOTVYb01Vd05jc2t5OWV1aHNzZ3pYWlFtaDloM2tiOVdxMkxTbjR3RFRuUnB0UGR4MUpnd1Fva2dxMzFmWENpUU5HbzUwRFB6Tw/messages/@original: 338.07 ms
03:17:32.815 [Debug] Loading icon from /app/Images/Icons/time.png
03:17:32.835 [Debug] Loading icon from /app/Images/Icons/audio.png
03:17:32.843 [Debug] Loading icon from /app/Images/Icons/repeat.png
03:17:32.865 [Warning] Failed to resolve track (will retry): Movie Night
03:17:32.865 [Warning] Failed to resolve track (will retry): Checkers
03:17:32.904 [Warning] Failed to resolve track (will retry): So Be It
03:17:32.926 [Warning] Failed to resolve track (will retry): Send Help
03:17:32.954 [Warning] Failed to resolve track (will retry): John Something
03:17:32.969 [Warning] Failed to resolve track (will retry): Ice Sold Here
03:17:33.007 [Warning] Failed to resolve track (will retry): Charlie Horse
03:17:33.095 [Warning] Failed to resolve track (will retry): The Red Phone
03:17:33.121 [Info] Retrying 8 failed tracks...
03:17:33.512 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_CREATE)
03:17:33.610 [Debug] [Discord] Rest: POST channels/237711887834415106/messages: 596.06 ms
03:17:33.618 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:17:35.123 [Error] Failed to resolve track after retry: Checkers — URL: http://192.168.11.17:32400/library/parts/155076/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:17:35.624 [Error] Failed to resolve track after retry: Movie Night — URL: http://192.168.11.17:32400/library/parts/155077/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:17:36.124 [Error] Failed to resolve track after retry: So Be It — URL: http://192.168.11.17:32400/library/parts/155080/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:17:36.627 [Error] Failed to resolve track after retry: Send Help — URL: http://192.168.11.17:32400/library/parts/155081/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:17:37.128 [Error] Failed to resolve track after retry: John Something — URL: http://192.168.11.17:32400/library/parts/155082/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:17:37.630 [Error] Failed to resolve track after retry: Ice Sold Here — URL: http://192.168.11.17:32400/library/parts/155083/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:17:38.130 [Error] Failed to resolve track after retry: Charlie Horse — URL: http://192.168.11.17:32400/library/parts/155087/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:17:38.630 [Error] Failed to resolve track after retry: The Red Phone — URL: http://192.168.11.17:32400/library/parts/155090/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:17:39.139 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:17:39.140 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:39.148 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:17:40.283 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:17:40.296 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503959362652209233: 921.88 ms
03:17:40.543 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:17:40.552 [Debug] [Discord] Rest: PATCH webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk1OTM1MjQ0MzQwNDQ4MDpuTVE1Y21zV2FqSUhEbWlIc0RjTEdWaExwREFvOGlONzhvQkpoRFRrRWZpSlZaQlBaS3dpSDFlOTVYb01Vd05jc2t5OWV1aHNzZ3pYWlFtaDloM2tiOVdxMkxTbjR3RFRuUnB0UGR4MUpnd1Fva2dxMzFmWENpUU5HbzUwRFB6Tw/messages/@original: 255.03 ms
03:17:40.554 [Debug] [Discord] App Commands: Executed Slash Command: "play" for [REDACTED] in [REDACTED]/general
03:17:42.786 [Debug] [Discord] Gateway: Sent Heartbeat
03:17:42.878 [Debug] [Discord] Gateway: Received HeartbeatAck
03:17:42.878 [Debug] [Discord] Gateway: Latency = 92 ms
03:17:45.528 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:17:45.531 [Debug] Interaction received: type=MessageComponent, elapsed=1ms since creation
03:17:45.537 [Debug] [Discord] App Commands: Executing Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:17:45.703 [Debug] [Discord] Rest: POST interactions/1503959413491241040/aW50ZXJhY3Rpb246MTUwMzk1OTQxMzQ5MTI0MTA0MDpKc3V1SGpoM3NPYTJhV1VxU2llZ2ozanlkVHBmSFR0aXZ0bEFubFNMaDJiOE9mc0pLamh2emgxOUEwY3RZUmgxTlRYT01UQ3RwS1BQTFpwaTc4aUtwRkN2eWx6dmZ0NkRIRkR4N2xmSjFIb0plMnFSN3N5bHlTMXZXMWNQaktOeA/callback: 163.17 ms
03:17:45.713 [Debug] Volume set to 30% by [REDACTED]
03:17:45.713 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:17:45.713 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:45.721 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:17:46.486 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:17:46.493 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503959362652209233: 530.71 ms
03:17:46.494 [Debug] [Discord] App Commands: Executed Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:17:55.446 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:17:55.447 [Debug] Interaction received: type=MessageComponent, elapsed=0ms since creation
03:17:55.447 [Debug] [Discord] App Commands: Executing Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:17:55.646 [Debug] [Discord] Rest: POST interactions/1503959454935421039/aW50ZXJhY3Rpb246MTUwMzk1OTQ1NDkzNTQyMTAzOTpjbUt0bnQ3cThRZG9acmUxRzcxanJRcm4ySDh2SjdVMlF1Q2l1Q1c2c1V6NlNlQUFpVlZMSmhsSjRWRGx6aEs4czBKWlJNR2lVWHhEc0lZaEtMSWpVMGNneEZVNTJsU0NSeDZZYUJ4VW9RSEtFZWo2Ulp1dzhGR2tKWlBuT3hXQg/callback: 198.24 ms
03:17:55.651 [Debug] Volume set to 40% by [REDACTED]
03:17:55.651 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:17:55.651 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:55.658 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:17:56.326 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:17:56.343 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503959362652209233: 590.72 ms
03:17:56.343 [Debug] [Discord] App Commands: Executed Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:17:58.024 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:17:58.025 [Debug] Interaction received: type=MessageComponent, elapsed=0ms since creation
03:17:58.025 [Debug] [Discord] App Commands: Executing Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:17:58.158 [Debug] [Discord] Rest: POST interactions/1503959465798402079/aW50ZXJhY3Rpb246MTUwMzk1OTQ2NTc5ODQwMjA3OTpTUUpQdzFEWFR5TzJlNmVGb2VZZ3pOZHgwcUtqeWE4UlUwY0tEMWxRTGVnQzVkYkJsWWhMb1V3NWNMMVI1R1BnQm1wUE93cE5taWp2ZzJOTjAyNnNqODJtNTNBa0JHeG9FQlNhVDBnVzJ3R0ZzNU1EZ25ON0FZS1ZNQlM0MW5uVw/callback: 131.94 ms
03:17:58.162 [Debug] Volume set to 50% by [REDACTED]
03:17:58.162 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:17:58.162 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:17:58.169 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:17:58.765 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:17:58.776 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503959362652209233: 497.82 ms
03:17:58.777 [Debug] [Discord] App Commands: Executed Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:08.880 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:18:08.881 [Debug] Interaction received: type=MessageComponent, elapsed=0ms since creation
03:18:08.881 [Debug] [Discord] App Commands: Executing Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:09.117 [Debug] [Discord] Rest: POST interactions/1503959511549874238/aW50ZXJhY3Rpb246MTUwMzk1OTUxMTU0OTg3NDIzODpKR1NmajRmNm5kYjlGZElxeHlKOTN0VnVJcXFSbXlnekowVUxuSkxjZ0lhb2FjNlVjaW9KYURBM1VCdkhYaklzMVFpMHo4QkR6U2MzaDFObVdMRkFzZlJOSlBRZHFYY3JXcU1ES0x6ME5NVTF0YXlyalQyZERPMVJVdlZxanU3YQ/callback: 236.01 ms
03:18:09.121 [Debug] Volume set to 60% by [REDACTED]
03:18:09.121 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:18:09.121 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:18:09.128 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:18:09.640 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:18:09.649 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503959362652209233: 411.36 ms
03:18:09.649 [Debug] [Discord] App Commands: Executed Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:10.202 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:18:10.202 [Debug] Interaction received: type=MessageComponent, elapsed=0ms since creation
03:18:10.203 [Debug] [Discord] App Commands: Executing Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:10.352 [Debug] [Discord] Rest: POST interactions/1503959516948070473/aW50ZXJhY3Rpb246MTUwMzk1OTUxNjk0ODA3MDQ3MzpqSE5Ra1U0ZGNpWnFFUWU0Ulg5RmdHNkJWUE44NWdQMkZLYjJMUm9OS1ZrUktDN2pPNUhETkdNRWZLeGtNdmwyeWdwTk9DT1d6dW1oWVg5cktoT3U2UnlJVWpVOUZmQWtvRFBCSmFscTZjVWE0NkVscWRTZEdhZ2hkWVFnd0YyMA/callback: 148.93 ms
03:18:10.543 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_CREATE)
03:18:10.550 [Debug] [Discord] Rest: POST webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk1OTUxNjk0ODA3MDQ3MzpqSE5Ra1U0ZGNpWnFFUWU0Ulg5RmdHNkJWUE44NWdQMkZLYjJMUm9OS1ZrUktDN2pPNUhETkdNRWZLeGtNdmwyeWdwTk9DT1d6dW1oWVg5cktoT3U2UnlJVWpVOUZmQWtvRFBCSmFscTZjVWE0NkVscWRTZEdhZ2hkWVFnd0YyMA?wait=true: 192.59 ms
03:18:10.552 [Debug] [Discord] App Commands: Executed Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:19.809 [Debug] [Discord] Gateway: Sent Heartbeat
03:18:19.911 [Debug] [Discord] Gateway: Received HeartbeatAck
03:18:19.911 [Debug] [Discord] Gateway: Latency = 100 ms
03:18:34.938 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:18:34.939 [Debug] Interaction received: type=MessageComponent, elapsed=0ms since creation
03:18:34.939 [Debug] [Discord] App Commands: Executing Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:35.091 [Debug] [Discord] Rest: POST interactions/1503959620715151442/aW50ZXJhY3Rpb246MTUwMzk1OTYyMDcxNTE1MTQ0MjpaMWtwcmNpMUtwMlhUa2pDU1hUNkh1MnF0YXBLb3l4M1JtVjhiU3NQc25KTXJic1NqUERQUHJsbkFKOTd0MURIOWM0Z0o4THRzempMQVN1VDA5VDliRk1CYTVtSHplMk1zcHVjUFhBZnA1eXhWZ0l2cjhUR3NJSWJpTDhEVndZOA/callback: 151.6 ms
03:18:35.096 [Debug] Volume set to 70% by [REDACTED]
03:18:35.096 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:18:35.096 [Debug] [ImageBuilder] Downloading bytes from http://192.168.11.17:32400/library/metadata/63158/thumb/1772101067?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:18:35.105 [Debug] [ImageBuilder] Downloaded 26375 bytes successfully
03:18:36.313 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:18:36.328 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503959362652209233: 1107.55 ms
03:18:36.329 [Debug] [Discord] App Commands: Executed Component Interaction: "volume:*" for [REDACTED] in [REDACTED]/general
03:18:40.350 [Debug] Player tracked state change for guild 237711887834415106: Tracked
03:18:40.351 [Debug] Track ended: Secret Knock, Reason: Cleanup
03:18:40.352 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:18:56.844 [Debug] [Discord] Gateway: Sent Heartbeat
03:18:56.927 [Debug] [Discord] Gateway: Received HeartbeatAck
03:18:56.927 [Debug] [Discord] Gateway: Latency = 84 ms
03:19:33.867 [Debug] [Discord] Gateway: Sent Heartbeat
03:19:33.942 [Debug] [Discord] Gateway: Received HeartbeatAck
03:19:33.942 [Debug] [Discord] Gateway: Latency = 76 ms
03:20:10.910 [Debug] [Discord] Gateway: Sent Heartbeat
03:20:10.996 [Debug] [Discord] Gateway: Received HeartbeatAck
03:20:10.996 [Debug] [Discord] Gateway: Latency = 88 ms
03:20:40.352 [Debug] [Discord] Gateway: Sent VoiceStateUpdate
03:20:40.355 [Info] Player inactive timeout reached for guild 237711887834415106, disconnecting...
03:20:40.355 [Error] Error handling player inactivity: Cannot access a disposed object.
Object name: 'PlexBot.Core.Services.LavaLink.CustomLavaLinkPlayer'.
03:20:40.462 [Debug] [Discord] Gateway: Received Dispatch (VOICE_STATE_UPDATE)
03:20:47.957 [Debug] [Discord] Gateway: Sent Heartbeat
03:20:48.042 [Debug] [Discord] Gateway: Received HeartbeatAck
03:20:48.042 [Debug] [Discord] Gateway: Latency = 84 ms
03:21:24.995 [Debug] [Discord] Gateway: Sent Heartbeat
03:21:25.077 [Debug] [Discord] Gateway: Received HeartbeatAck
03:21:25.077 [Debug] [Discord] Gateway: Latency = 84 ms
03:22:02.036 [Debug] [Discord] Gateway: Sent Heartbeat
03:22:02.120 [Debug] [Discord] Gateway: Received HeartbeatAck
03:22:02.120 [Debug] [Discord] Gateway: Latency = 84 ms
03:22:39.077 [Debug] [Discord] Gateway: Sent Heartbeat
03:22:39.160 [Debug] [Discord] Gateway: Received HeartbeatAck
03:22:39.160 [Debug] [Discord] Gateway: Latency = 80 ms
03:23:16.122 [Debug] [Discord] Gateway: Sent Heartbeat
03:23:16.205 [Debug] [Discord] Gateway: Received HeartbeatAck
03:23:16.205 [Debug] [Discord] Gateway: Latency = 88 ms
03:24:13.666 [Init] Loading configuration from /app/.env
03:24:13.673 [Init] Configuration initialized with 28 values
03:24:13.674 [Init] Loading bot config from /app/config.fds
03:24:13.689 [Info] Log saving started. Writing logs to: logs/plex-bot-2026-05-13.log
03:24:13.689 [Init] Starting Plex Music Bot
03:24:13.719 [Init] Registering services
03:24:13.761 [Info] Found 0 extension directories in /source/Extensions
03:24:13.761 [Info] Discovered 0 extensions total
03:24:13.761 [Init] Services registered
03:24:13.793 [Init] Host built, starting services
03:24:13.816 [Debug] Button registered: vol_up with flags VisualPlayer and priority 10
03:24:13.816 [Debug] Button registered: pause_resume with flags VisualPlayer and priority 20
03:24:13.816 [Debug] Button registered: skip with flags VisualPlayer and priority 30
03:24:13.816 [Debug] Button registered: repeat with flags VisualPlayer and priority 40
03:24:13.816 [Debug] Button registered: queue_options with flags VisualPlayer and priority 50
03:24:13.816 [Debug] Button registered: vol_down with flags VisualPlayer and priority 60
03:24:13.816 [Debug] Button registered: radio with flags VisualPlayer and priority 65
03:24:13.816 [Debug] Button registered: similar with flags VisualPlayer and priority 66
03:24:13.816 [Debug] Button registered: adventure with flags VisualPlayer and priority 67
03:24:13.816 [Debug] Button registered: kill with flags VisualPlayer and priority 70
03:24:13.816 [Debug] Button registered: view_queue with flags QueueOptions and priority 10
03:24:13.816 [Debug] Button registered: shuffle_queue with flags QueueOptions and priority 20
03:24:13.816 [Debug] Button registered: clear_queue with flags QueueOptions and priority 30
03:24:13.840 [Init] Starting bot service
03:24:13.840 [Init] Discord event handlers initialized
03:24:13.840 [Init] Lavalink services initialized
03:24:13.842 [Info] Successfully initialized 0 of 0 extensions
03:24:13.842 [Info] Initialized 0 extensions
03:24:13.846 [Init] PlexApiService initialized with server URL: http://192.168.11.17:32400
03:24:13.846 [Info] Music provider registered: Plex (plex)
03:24:13.847 [Init] Registered 1 music providers
03:24:13.847 [Init] Connecting to Discord
03:24:13.849 [Info] [Discord] Discord: Discord.Net v3.19.0 (API v10)
03:24:13.853 [Init] Bot service started
03:24:13.854 [Debug] Static player channel is not configured or invalid, skipping initialization
03:24:13.854 [Info] [Discord] Gateway: Connecting
03:24:13.855 [Debug] [Discord] Gateway: Connecting ApiClient
03:24:14.154 [Debug] [Discord] Rest: GET gateway/bot: 278.78 ms
03:24:14.445 [Debug] [Discord] Gateway: Received Hello
03:24:14.448 [Debug] [Discord] Gateway: Heartbeat Started
03:24:14.458 [Debug] [Discord] Gateway: Identifying
03:24:14.461 [Debug] [Discord] Gateway: Sent Heartbeat
03:24:14.468 [Debug] [Discord] Gateway: Sent Identify
03:24:14.537 [Debug] [Discord] Gateway: Received HeartbeatAck
03:24:14.539 [Debug] [Discord] Gateway: Latency = 92 ms
03:24:14.798 [Debug] [Discord] Gateway: Received Dispatch (READY)
03:24:14.823 [Debug] [Discord] Gateway: GuildDownloader Started
03:24:14.825 [Info] [Discord] Gateway: Connected
03:24:14.825 [Debug] [Discord] Gateway: Raising Event
03:24:14.860 [Debug] [Discord] Gateway: Received Dispatch (GUILD_AVAILABLE)
03:24:14.868 [Debug] [Discord] Gateway: Connected to [REDACTED]
03:24:15.324 [Debug] [Discord] Gateway: GuildDownloader Stopped
03:24:15.371 [Debug] [Discord] App Commands: Successfully built 2 interaction modules.
03:24:15.380 [Info] Discovered 2 interaction modules
03:24:15.380 [Info] Module: MusicInteractionHandler, Commands: 0
03:24:15.380 [Info] Module: MusicCommands, Commands: 5
03:24:15.380 [Info] Command: search
03:24:15.380 [Info] Command: playlist
03:24:15.380 [Info] Command: play
03:24:15.380 [Info] Command: ping
03:24:15.380 [Info] Command: help
03:24:15.621 [Debug] [Discord] Rest: PUT applications/[REDACTED]/commands: 216.57 ms
03:24:15.632 [Info] Registered commands globally
03:24:15.638 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:24:15.638 [Debug] Event bus: subscribed to 'track.started'
03:24:15.638 [Debug] Event bus: subscribed to 'track.ended'
03:24:15.638 [Debug] Event bus: subscribed to 'player.destroyed'
03:24:15.638 [Init] Rich presence enabled — bot status will show now-playing track
03:24:15.639 [Init] Bot is ready. Connected to 1 guilds
03:24:15.640 [Info] [Discord] Gateway: Ready
03:24:21.643 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:24:21.663 [Debug] Interaction received: type=ApplicationCommand, elapsed=3ms since creation
03:24:21.668 [Debug] [Discord] App Commands: Executing Slash Command: "play" for [REDACTED] in [REDACTED]/general
03:24:21.893 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_CREATE)
03:24:21.912 [Debug] [Discord] Rest: POST interactions/1503961074788077569/aW50ZXJhY3Rpb246MTUwMzk2MTA3NDc4ODA3NzU2OTpsZ3h5VE5sZDNvOXZHYVFmNjRNVnNVY0FDZEJaQ2FWNUVKSlUxZUZteUswMHk4YWd5V0VSb2tuUDhhbVhXSG0zNGtBZ2ZiNGhhc1ZZM1BxaWV3T0xSUzNabmllSkNybkdQeGt6dExTbDVnQ1BPRkJzMGVmMTRyMkh3dlp3QldKeg/callback: 232.8 ms
03:24:21.912 [Info] Play command: aesop rock
03:24:21.914 [Info] Searching Plex library for: aesop rock
03:24:21.915 [Debug] Performing Plex API request to: http://192.168.11.17:32400/hubs/search?query=aesop+rock&limit=100&X-Plex-Token=[REDACTED]
03:24:21.916 [Debug] [PlexAPI] GET request to http://192.168.11.17:32400/hubs/search?query=aesop+rock&limit=100&X-Plex-Token=[REDACTED] (Attempt 1/4)
03:24:22.011 [Debug] [PlexAPI] Request successful
03:24:22.012 [Debug] Received Plex API response (18826 bytes)
03:24:22.014 [Debug] Processing hub: Artists (artist)
03:24:22.015 [Debug] Processing hub: Shows (show)
03:24:22.015 [Debug] Processing hub: Albums (album)
03:24:22.015 [Debug] Processing hub: Episodes (episode)
03:24:22.015 [Debug] Processing hub: Movies (movie)
03:24:22.015 [Debug] Processing hub: Tracks (track)
03:24:22.015 [Debug] Processing hub: Photo Albums (photoalbum)
03:24:22.015 [Debug] Processing hub: Automatic Tags (autotag)
03:24:22.015 [Debug] Processing hub: Photos (photo)
03:24:22.015 [Debug] Processing hub: Tags (tag)
03:24:22.015 [Debug] Processing hub: Actors (actor)
03:24:22.015 [Debug] Processing hub: Directors (director)
03:24:22.015 [Debug] Processing hub: Genres (genre)
03:24:22.015 [Debug] Processing hub: Collections (collection)
03:24:22.015 [Debug] Processing hub: Playlists (playlist)
03:24:22.015 [Debug] Processing hub: Shared (shared)
03:24:22.015 [Debug] Processing hub: Places (place)
03:24:22.015 [Debug] Parsed search results: 1 artists, 3 albums, 0 tracks, 0 playlists
03:24:22.015 [Info] Search complete. Found 1 artists, 3 albums, 0 tracks, 0 playlists
03:24:22.017 [Debug] Getting tracks for container: /library/metadata/63158/children
03:24:22.017 [Debug] Performing Plex API request to: http://192.168.11.17:32400/library/metadata/63158/children?X-Plex-Token=[REDACTED]
03:24:22.017 [Debug] [PlexAPI] GET request to http://192.168.11.17:32400/library/metadata/63158/children?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:24:22.028 [Debug] [PlexAPI] Request successful
03:24:22.028 [Debug] Received Plex API response (30061 bytes)
03:24:22.034 [Debug] Retrieved 18 tracks
03:24:22.042 [Debug] [Discord] Gateway: Sent VoiceStateUpdate
03:24:22.173 [Debug] [Discord] Gateway: Received Dispatch (VOICE_STATE_UPDATE)
03:24:22.269 [Debug] [Discord] Gateway: Received Dispatch (VOICE_SERVER_UPDATE)
03:24:22.805 [Debug] Player tracked state change for guild 237711887834415106: Tracked
03:24:23.042 [Debug] [Discord] Rest: GET webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk2MTA3NDc4ODA3NzU2OTpsZ3h5VE5sZDNvOXZHYVFmNjRNVnNVY0FDZEJaQ2FWNUVKSlUxZUZteUswMHk4YWd5V0VSb2tuUDhhbVhXSG0zNGtBZ2ZiNGhhc1ZZM1BxaWV3T0xSUzNabmllSkNybkdQeGt6dExTbDVnQ1BPRkJzMGVmMTRyMkh3dlp3QldKeg/messages/@original: 219.82 ms
03:24:23.059 [Debug] Adding 18 tracks to queue
03:24:23.147 [Debug] Playing first track: Secret Knock by Aesop Rock
03:24:23.165 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:24:23.168 [Debug] Player active event for guild 237711887834415106
03:24:23.170 [Info] Progress bar size: medium (16 segments)
03:24:23.170 [Info] Progress bar: Using unicode fallback (no custom emoji IDs configured)
03:24:23.416 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_CREATE)
03:24:23.454 [Debug] [Discord] Rest: POST channels/237711887834415106/messages: 263.48 ms
03:24:23.462 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:24:23.517 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:24:23.694 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:24:23.713 [Debug] [Discord] Rest: PATCH webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk2MTA3NDc4ODA3NzU2OTpsZ3h5VE5sZDNvOXZHYVFmNjRNVnNVY0FDZEJaQ2FWNUVKSlUxZUZteUswMHk4YWd5V0VSb2tuUDhhbVhXSG0zNGtBZ2ZiNGhhc1ZZM1BxaWV3T0xSUzNabmllSkNybkdQeGt6dExTbDVnQ1BPRkJzMGVmMTRyMkh3dlp3QldKeg/messages/@original: 528.92 ms
03:24:23.763 [Warning] Failed to resolve track (will retry): Checkers
03:24:23.776 [Warning] Failed to resolve track (will retry): EWR - Terminal A, Gate 20
03:24:23.791 [Warning] Failed to resolve track (will retry): 1010WINS
03:24:23.806 [Warning] Failed to resolve track (will retry): So Be It
03:24:23.823 [Warning] Failed to resolve track (will retry): Send Help
03:24:23.901 [Warning] Failed to resolve track (will retry): Bird School
03:24:23.944 [Warning] Failed to resolve track (will retry): Steel Wool
03:24:23.987 [Warning] Failed to resolve track (will retry): The Red Phone
03:24:23.988 [Info] Retrying 8 failed tracks...
03:24:25.991 [Error] Failed to resolve track after retry: Checkers — URL: http://192.168.11.17:32400/library/parts/155076/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:24:26.492 [Error] Failed to resolve track after retry: EWR - Terminal A, Gate 20 — URL: http://192.168.11.17:32400/library/parts/155078/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:24:26.992 [Error] Failed to resolve track after retry: 1010WINS — URL: http://192.168.11.17:32400/library/parts/155079/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:24:27.493 [Error] Failed to resolve track after retry: So Be It — URL: http://192.168.11.17:32400/library/parts/155080/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:24:27.993 [Error] Failed to resolve track after retry: Send Help — URL: http://192.168.11.17:32400/library/parts/155081/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:24:28.494 [Error] Failed to resolve track after retry: Bird School — URL: http://192.168.11.17:32400/library/parts/155085/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:24:28.993 [Error] Failed to resolve track after retry: Steel Wool — URL: http://192.168.11.17:32400/library/parts/155088/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:24:29.494 [Error] Failed to resolve track after retry: The Red Phone — URL: http://192.168.11.17:32400/library/parts/155090/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:24:29.998 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:24:30.188 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:24:30.189 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503961082354470945: 181.4 ms
03:24:30.394 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:24:30.413 [Debug] [Discord] Rest: PATCH webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk2MTA3NDc4ODA3NzU2OTpsZ3h5VE5sZDNvOXZHYVFmNjRNVnNVY0FDZEJaQ2FWNUVKSlUxZUZteUswMHk4YWd5V0VSb2tuUDhhbVhXSG0zNGtBZ2ZiNGhhc1ZZM1BxaWV3T0xSUzNabmllSkNybkdQeGt6dExTbDVnQ1BPRkJzMGVmMTRyMkh3dlp3QldKeg/messages/@original: 222.35 ms
03:24:30.414 [Debug] [Discord] App Commands: Executed Slash Command: "play" for [REDACTED] in [REDACTED]/general
03:24:51.582 [Debug] [Discord] Gateway: Sent Heartbeat
03:24:51.660 [Debug] [Discord] Gateway: Received HeartbeatAck
03:24:51.660 [Debug] [Discord] Gateway: Latency = 76 ms
03:25:28.428 [Debug] Player tracked state change for guild 237711887834415106: Tracked
03:25:28.429 [Debug] Track ended: Secret Knock, Reason: Cleanup
03:25:28.430 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:25:28.616 [Debug] [Discord] Gateway: Sent Heartbeat
03:25:28.690 [Debug] [Discord] Gateway: Received HeartbeatAck
03:25:28.690 [Debug] [Discord] Gateway: Latency = 76 ms
03:26:05.664 [Debug] [Discord] Gateway: Sent Heartbeat
03:26:05.746 [Debug] [Discord] Gateway: Received HeartbeatAck
03:26:05.746 [Debug] [Discord] Gateway: Latency = 80 ms
03:26:42.713 [Debug] [Discord] Gateway: Sent Heartbeat
03:26:42.791 [Debug] [Discord] Gateway: Received HeartbeatAck
03:26:42.791 [Debug] [Discord] Gateway: Latency = 76 ms
03:27:19.761 [Debug] [Discord] Gateway: Sent Heartbeat
03:27:19.840 [Debug] [Discord] Gateway: Received HeartbeatAck
03:27:19.840 [Debug] [Discord] Gateway: Latency = 80 ms
03:27:28.434 [Debug] [Discord] Gateway: Sent VoiceStateUpdate
03:27:28.437 [Info] Player inactive timeout reached for guild 237711887834415106, disconnecting...
03:27:28.437 [Error] Error handling player inactivity: Cannot access a disposed object.
Object name: 'PlexBot.Core.Services.LavaLink.CustomLavaLinkPlayer'.
03:27:28.562 [Debug] [Discord] Gateway: Received Dispatch (VOICE_STATE_UPDATE)
03:27:41.596 [Debug] [Discord] Gateway: Received Dispatch (INTERACTION_CREATE)
03:27:41.597 [Debug] Interaction received: type=ApplicationCommand, elapsed=0ms since creation
03:27:41.597 [Debug] [Discord] App Commands: Executing Slash Command: "play" for [REDACTED] in [REDACTED]/general
03:27:41.827 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_CREATE)
03:27:41.834 [Debug] [Discord] Rest: POST interactions/1503961913267064956/aW50ZXJhY3Rpb246MTUwMzk2MTkxMzI2NzA2NDk1NjpTQVVhbGJKbFpMdWhObzNYUmJvM0dZTmhqQVZGbVFtc0dJcmpVZVhaT1hDZXR1a0JHeVppU3RqUmc4RktIVXFHRVpKNEJjYk1STkRtOExYTktBTDl6SE5ZMHVOZnlDM0F0STNKYlAwUWh3cmVwMUxiR00xdWd6OUExaHlGdzR6cA/callback: 235.76 ms
03:27:41.834 [Info] Play command: aesop rock
03:27:41.834 [Info] Searching Plex library for: aesop rock
03:27:41.834 [Debug] Performing Plex API request to: http://192.168.11.17:32400/hubs/search?query=aesop+rock&limit=100&X-Plex-Token=[REDACTED]
03:27:41.834 [Debug] [PlexAPI] GET request to http://192.168.11.17:32400/hubs/search?query=aesop+rock&limit=100&X-Plex-Token=[REDACTED] (Attempt 1/4)
03:27:41.898 [Debug] [PlexAPI] Request successful
03:27:41.898 [Debug] Received Plex API response (18826 bytes)
03:27:41.898 [Debug] Processing hub: Artists (artist)
03:27:41.898 [Debug] Processing hub: Shows (show)
03:27:41.898 [Debug] Processing hub: Albums (album)
03:27:41.898 [Debug] Processing hub: Episodes (episode)
03:27:41.898 [Debug] Processing hub: Movies (movie)
03:27:41.898 [Debug] Processing hub: Tracks (track)
03:27:41.898 [Debug] Processing hub: Photo Albums (photoalbum)
03:27:41.898 [Debug] Processing hub: Automatic Tags (autotag)
03:27:41.898 [Debug] Processing hub: Photos (photo)
03:27:41.898 [Debug] Processing hub: Tags (tag)
03:27:41.898 [Debug] Processing hub: Actors (actor)
03:27:41.898 [Debug] Processing hub: Directors (director)
03:27:41.898 [Debug] Processing hub: Genres (genre)
03:27:41.898 [Debug] Processing hub: Collections (collection)
03:27:41.898 [Debug] Processing hub: Playlists (playlist)
03:27:41.898 [Debug] Processing hub: Shared (shared)
03:27:41.898 [Debug] Processing hub: Places (place)
03:27:41.898 [Debug] Parsed search results: 1 artists, 3 albums, 0 tracks, 0 playlists
03:27:41.898 [Info] Search complete. Found 1 artists, 3 albums, 0 tracks, 0 playlists
03:27:41.898 [Debug] Getting tracks for container: /library/metadata/63158/children
03:27:41.898 [Debug] Performing Plex API request to: http://192.168.11.17:32400/library/metadata/63158/children?X-Plex-Token=[REDACTED]
03:27:41.898 [Debug] [PlexAPI] GET request to http://192.168.11.17:32400/library/metadata/63158/children?X-Plex-Token=[REDACTED] (Attempt 1/4)
03:27:41.917 [Debug] [PlexAPI] Request successful
03:27:41.917 [Debug] Received Plex API response (30061 bytes)
03:27:41.918 [Debug] Retrieved 18 tracks
03:27:41.918 [Debug] [Discord] Gateway: Sent VoiceStateUpdate
03:27:42.005 [Debug] [Discord] Gateway: Received Dispatch (VOICE_STATE_UPDATE)
03:27:42.055 [Debug] [Discord] Gateway: Received Dispatch (VOICE_SERVER_UPDATE)
03:27:42.265 [Debug] Player tracked state change for guild 237711887834415106: Tracked
03:27:42.405 [Debug] [Discord] Rest: GET webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk2MTkxMzI2NzA2NDk1NjpTQVVhbGJKbFpMdWhObzNYUmJvM0dZTmhqQVZGbVFtc0dJcmpVZVhaT1hDZXR1a0JHeVppU3RqUmc4RktIVXFHRVpKNEJjYk1STkRtOExYTktBTDl6SE5ZMHVOZnlDM0F0STNKYlAwUWh3cmVwMUxiR00xdWd6OUExaHlGdzR6cA/messages/@original: 133.79 ms
03:27:42.405 [Debug] Adding 18 tracks to queue
03:27:42.406 [Debug] Resolve cache hit: Secret Knock
03:27:42.406 [Debug] Playing first track: Secret Knock by Aesop Rock
03:27:42.410 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:27:42.411 [Debug] Player active event for guild 237711887834415106
03:27:42.796 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:27:42.811 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503961082354470945: 400.16 ms
03:27:42.812 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:27:42.973 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:27:43.037 [Debug] [Discord] Rest: PATCH webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk2MTkxMzI2NzA2NDk1NjpTQVVhbGJKbFpMdWhObzNYUmJvM0dZTmhqQVZGbVFtc0dJcmpVZVhaT1hDZXR1a0JHeVppU3RqUmc4RktIVXFHRVpKNEJjYk1STkRtOExYTktBTDl6SE5ZMHVOZnlDM0F0STNKYlAwUWh3cmVwMUxiR00xdWd6OUExaHlGdzR6cA/messages/@original: 625.64 ms
03:27:43.037 [Warning] Failed to resolve track (will retry): Checkers
03:27:43.037 [Debug] Resolve cache hit: Movie Night
03:27:43.037 [Warning] Failed to resolve track (will retry): EWR - Terminal A, Gate 20
03:27:43.037 [Warning] Failed to resolve track (will retry): 1010WINS
03:27:43.037 [Warning] Failed to resolve track (will retry): So Be It
03:27:43.037 [Warning] Failed to resolve track (will retry): Send Help
03:27:43.037 [Debug] Resolve cache hit: John Something
03:27:43.037 [Debug] Resolve cache hit: Ice Sold Here
03:27:43.037 [Debug] Resolve cache hit: Costco
03:27:43.037 [Warning] Failed to resolve track (will retry): Bird School
03:27:43.037 [Debug] Resolve cache hit: Snail Zero
03:27:43.037 [Debug] Resolve cache hit: Charlie Horse
03:27:43.037 [Warning] Failed to resolve track (will retry): Steel Wool
03:27:43.037 [Debug] Resolve cache hit: Black Plums
03:27:43.037 [Warning] Failed to resolve track (will retry): The Red Phone
03:27:43.037 [Debug] Resolve cache hit: Himalayan Yak Chew
03:27:43.037 [Debug] Resolve cache hit: Unbelievable Shenanigans
03:27:43.038 [Info] Retrying 8 failed tracks...
03:27:45.035 [Error] Failed to resolve track after retry: Checkers — URL: http://192.168.11.17:32400/library/parts/155076/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:27:45.536 [Error] Failed to resolve track after retry: EWR - Terminal A, Gate 20 — URL: http://192.168.11.17:32400/library/parts/155078/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:27:46.036 [Error] Failed to resolve track after retry: 1010WINS — URL: http://192.168.11.17:32400/library/parts/155079/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:27:46.537 [Error] Failed to resolve track after retry: So Be It — URL: http://192.168.11.17:32400/library/parts/155080/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:27:47.037 [Error] Failed to resolve track after retry: Send Help — URL: http://192.168.11.17:32400/library/parts/155081/1768890925/file.mp3?X-Plex-Token=[REDACTED]
03:27:47.536 [Error] Failed to resolve track after retry: Bird School — URL: http://192.168.11.17:32400/library/parts/155085/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:27:48.036 [Error] Failed to resolve track after retry: Steel Wool — URL: http://192.168.11.17:32400/library/parts/155088/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:27:48.536 [Error] Failed to resolve track after retry: The Red Phone — URL: http://192.168.11.17:32400/library/parts/155090/1768890926/file.mp3?X-Plex-Token=[REDACTED]
03:27:49.037 [Debug] Building components with flags VisualPlayer, found 10 matching buttons
03:27:49.226 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:27:49.238 [Debug] [Discord] Rest: PATCH channels/237711887834415106/messages/1503961082354470945: 200.55 ms
03:27:49.457 [Debug] [Discord] Gateway: Received Dispatch (MESSAGE_UPDATE)
03:27:49.472 [Debug] [Discord] Rest: PATCH webhooks/[REDACTED]/aW50ZXJhY3Rpb246MTUwMzk2MTkxMzI2NzA2NDk1NjpTQVVhbGJKbFpMdWhObzNYUmJvM0dZTmhqQVZGbVFtc0dJcmpVZVhaT1hDZXR1a0JHeVppU3RqUmc4RktIVXFHRVpKNEJjYk1STkRtOExYTktBTDl6SE5ZMHVOZnlDM0F0STNKYlAwUWh3cmVwMUxiR00xdWd6OUExaHlGdzR6cA/messages/@original: 233.35 ms
03:27:49.473 [Debug] [Discord] App Commands: Executed Slash Command: "play" for [REDACTED] in [REDACTED]/general
03:27:56.809 [Debug] [Discord] Gateway: Sent Heartbeat
03:27:56.887 [Debug] [Discord] Gateway: Received HeartbeatAck
03:27:56.887 [Debug] [Discord] Gateway: Latency = 76 ms
03:28:33.858 [Debug] [Discord] Gateway: Sent Heartbeat
03:28:33.950 [Debug] [Discord] Gateway: Received HeartbeatAck
03:28:33.950 [Debug] [Discord] Gateway: Latency = 92 ms
03:28:48.417 [Debug] Track ended: Secret Knock, Reason: Cleanup
03:28:48.418 [Debug] Player tracked state change for guild 237711887834415106: Tracked
03:28:48.418 [Debug] [Discord] Gateway: Sent PresenceUpdate
03:29:10.906 [Debug] [Discord] Gateway: Sent Heartbeat
03:29:10.977 [Debug] [Discord] Gateway: Received HeartbeatAck
03:29:10.977 [Debug] [Discord] Gateway: Latency = 72 ms
03:29:47.939 [Debug] [Discord] Gateway: Sent Heartbeat
03:29:48.017 [Debug] [Discord] Gateway: Received HeartbeatAck
03:29:48.017 [Debug] [Discord] Gateway: Latency = 80 ms
03:30:24.990 [Debug] [Discord] Gateway: Sent Heartbeat
03:30:25.073 [Debug] [Discord] Gateway: Received HeartbeatAck
03:30:25.073 [Debug] [Discord] Gateway: Latency = 80 ms
03:30:48.416 [Debug] [Discord] Gateway: Sent VoiceStateUpdate
03:30:48.416 [Info] Player inactive timeout reached for guild 237711887834415106, disconnecting...
03:30:48.416 [Error] Error handling player inactivity: Cannot access a disposed object.
Object name: 'PlexBot.Core.Services.LavaLink.CustomLavaLinkPlayer'.
03:30:48.542 [Debug] [Discord] Gateway: Received Dispatch (VOICE_STATE_UPDATE)
03:31:02.037 [Debug] [Discord] Gateway: Sent Heartbeat
03:31:02.117 [Debug] [Discord] Gateway: Received HeartbeatAck
03:31:02.117 [Debug] [Discord] Gateway: Latency = 80 ms
03:31:39.081 [Debug] [Discord] Gateway: Sent Heartbeat
03:31:39.158 [Debug] [Discord] Gateway: Received HeartbeatAck
03:31:39.158 [Debug] [Discord] Gateway: Latency = 76 ms
03:32:16.126 [Debug] [Discord] Gateway: Sent Heartbeat
03:32:16.225 [Debug] [Discord] Gateway: Received HeartbeatAck
03:32:16.225 [Debug] [Discord] Gateway: Latency = 100 ms
03:32:53.175 [Debug] [Discord] Gateway: Sent Heartbeat
03:32:53.255 [Debug] [Discord] Gateway: Received HeartbeatAck
03:32:53.255 [Debug] [Discord] Gateway: Latency = 80 ms
03:33:30.200 [Debug] [Discord] Gateway: Sent Heartbeat
03:33:30.286 [Debug] [Discord] Gateway: Received HeartbeatAck
03:33:30.286 [Debug] [Discord] Gateway: Latency = 88 ms
03:34:07.242 [Debug] [Discord] Gateway: Sent Heartbeat
03:34:07.324 [Debug] [Discord] Gateway: Received HeartbeatAck
03:34:07.324 [Debug] [Discord] Gateway: Latency = 76 ms
03:34:44.278 [Debug] [Discord] Gateway: Sent Heartbeat
03:34:44.360 [Debug] [Discord] Gateway: Received HeartbeatAck
03:34:44.360 [Debug] [Discord] Gateway: Latency = 80 ms
03:35:21.326 [Debug] [Discord] Gateway: Sent Heartbeat
03:35:21.411 [Debug] [Discord] Gateway: Received HeartbeatAck
03:35:21.411 [Debug] [Discord] Gateway: Latency = 84 ms
03:35:58.374 [Debug] [Discord] Gateway: Sent Heartbeat
03:35:58.455 [Debug] [Discord] Gateway: Received HeartbeatAck
03:35:58.455 [Debug] [Discord] Gateway: Latency = 80 ms
03:36:35.412 [Debug] [Discord] Gateway: Sent Heartbeat
03:36:35.491 [Debug] [Discord] Gateway: Received HeartbeatAck
03:36:35.491 [Debug] [Discord] Gateway: Latency = 76 ms
03:37:12.459 [Debug] [Discord] Gateway: Sent Heartbeat
03:37:12.546 [Debug] [Discord] Gateway: Received HeartbeatAck
03:37:12.546 [Debug] [Discord] Gateway: Latency = 88 ms
03:37:49.506 [Debug] [Discord] Gateway: Sent Heartbeat
03:37:49.586 [Debug] [Discord] Gateway: Received HeartbeatAck
03:37:49.586 [Debug] [Discord] Gateway: Latency = 76 ms
03:38:26.546 [Debug] [Discord] Gateway: Sent Heartbeat
03:38:26.617 [Debug] [Discord] Gateway: Received HeartbeatAck
03:38:26.617 [Debug] [Discord] Gateway: Latency = 72 ms
03:39:03.593 [Debug] [Discord] Gateway: Sent Heartbeat
03:39:03.673 [Debug] [Discord] Gateway: Received HeartbeatAck
03:39:03.673 [Debug] [Discord] Gateway: Latency = 80 ms
03:39:40.646 [Debug] [Discord] Gateway: Sent Heartbeat
03:39:40.723 [Debug] [Discord] Gateway: Received HeartbeatAck
03:39:40.723 [Debug] [Discord] Gateway: Latency = 76 ms
Shows that the Plexbot is NOT turned all the way down, or is it muted from my client-end, nor is it server-muted.
Description
When the bot joins the Discord channel, after sending a Play command, it joins the channel in a muted state.
Steps to Reproduce
Expected Behavior
Bot joins voice channel and is not client-side muted and plays songs as expected
Actual Behavior
Bot joins voice channel, as expected, but is client-side muted. It appears to attempt to play the first few seconds of songs which were located by the query and exits the channel after some time without playing music.
Logs
Player Style
Both / Not applicable
Deployment Method
Docker (install script)
Operating System
Windows 10
Additional Context
Shows the Plexbot is client-side muted
Shows that the Plexbot is NOT turned all the way down, or is it muted from my client-end, nor is it server-muted.
Shows the permissions for the Plexbot
