[ x] I am using Python 3.5 or higher (run python --version on the command line)
[ x] I have followed the official guides to install the bot for my system
[x ] I have updated my dependencies to the latest version using the appropriate update script
Which version are you using?
[ x] The latest master version (1.9.7)
[ ] The latest review version
What type of issue are you creating?
[ x] Bug
[ ] Feature request
[ ] Question
Description of issue
WARNING: i18n file does not exist. Trying to fallback to config/i18n/en.json.
Fatal read error on socket transport
protocol: <asyncio.sslproto.SSLProtocol object at 0x7f672e38bbe0>
transport: <_SelectorSocketTransport fd=11 read=polling write=<idle, bufsize=0>>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready
data = self._sock.recv(self.max_size)
TimeoutError: [Errno 110] Connection timed out
ERROR: This video is unavailable.
Task exception was never retrieved
future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=TimeoutError()>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step
result = coro.send(None)
File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws
yield from self.ws.poll_event()
File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event
msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop)
File "/usr/lib/python3.5/asyncio/tasks.py", line 396, in wait_for
raise futures.TimeoutError()
concurrent.futures._base.TimeoutError
Task exception was never retrieved
future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=TimeoutError()>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step
result = coro.send(None)
File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws
yield from self.ws.poll_event()
File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event
msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop)
File "/usr/lib/python3.5/asyncio/tasks.py", line 396, in wait_for
raise futures.TimeoutError()
concurrent.futures._base.TimeoutError
Task exception was never retrieved
future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step
result = coro.throw(exc)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 434, in run
msg = yield from self.read_message()
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 456, in read_message
frame = yield from self.read_data_frame(max_size=self.max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 511, in read_data_frame
frame = yield from self.read_frame(max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 546, in read_frame
self.reader.readexactly, is_masked, max_size=max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/framing.py", line 86, in read_frame
data = yield from reader(2)
File "/usr/lib/python3.5/asyncio/streams.py", line 670, in readexactly
block = yield from self.read(n)
File "/usr/lib/python3.5/asyncio/streams.py", line 627, in read
yield from self._wait_for_data('read')
File "/usr/lib/python3.5/asyncio/streams.py", line 457, in _wait_for_data
yield from self._waiter
File "/usr/lib/python3.5/asyncio/futures.py", line 361, in iter
yield self # This tells Task to wait for completion.
File "/usr/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
future.result()
File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result
raise self._exception
File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready
data = self._sock.recv(self.max_size)
ConnectionResetError: [Errno 104] Connection reset by peer
Task exception was never retrieved
future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step
result = coro.throw(exc)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 434, in run
msg = yield from self.read_message()
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 456, in read_message
frame = yield from self.read_data_frame(max_size=self.max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 511, in read_data_frame
frame = yield from self.read_frame(max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 546, in read_frame
self.reader.readexactly, is_masked, max_size=max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/framing.py", line 86, in read_frame
data = yield from reader(2)
File "/usr/lib/python3.5/asyncio/streams.py", line 670, in readexactly
block = yield from self.read(n)
File "/usr/lib/python3.5/asyncio/streams.py", line 627, in read
yield from self._wait_for_data('read')
File "/usr/lib/python3.5/asyncio/streams.py", line 457, in _wait_for_data
yield from self._waiter
File "/usr/lib/python3.5/asyncio/futures.py", line 361, in iter
yield self # This tells Task to wait for completion.
File "/usr/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
future.result()
File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result
raise self._exception
File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready
data = self._sock.recv(self.max_size)
ConnectionResetError: [Errno 104] Connection reset by peer
ERROR: This video contains content from SME, who has blocked it in your country on copyright grounds.
Task exception was never retrieved
future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step
result = coro.throw(exc)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 434, in run
msg = yield from self.read_message()
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 456, in read_message
frame = yield from self.read_data_frame(max_size=self.max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 511, in read_data_frame
frame = yield from self.read_frame(max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 546, in read_frame
self.reader.readexactly, is_masked, max_size=max_size)
File "/usr/local/lib/python3.5/dist-packages/websockets/framing.py", line 86, in read_frame
data = yield from reader(2)
File "/usr/lib/python3.5/asyncio/streams.py", line 670, in readexactly
block = yield from self.read(n)
File "/usr/lib/python3.5/asyncio/streams.py", line 627, in read
yield from self._wait_for_data('read')
File "/usr/lib/python3.5/asyncio/streams.py", line 457, in _wait_for_data
yield from self._waiter
File "/usr/lib/python3.5/asyncio/futures.py", line 361, in iter
yield self # This tells Task to wait for completion.
File "/usr/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup
future.result()
File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result
raise self._exception
File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready
data = self._sock.recv(self.max_size)
ConnectionResetError: [Errno 104] Connection reset by peer
ERROR: Unsupported URL: https://open.spotify.com/track/6oxGueIQWk8hmYpStfgGww?si=JSATbtlnQjOO5PWJKDFRgQ
Task exception was never retrieved
future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=ConnectionClosed('WebSocket connection is closed: code = 1006, no reason.',)>
Traceback (most recent call last):
File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event
msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop)
File "/usr/lib/python3.5/asyncio/tasks.py", line 392, in wait_for
return fut.result()
File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result
raise self._exception
File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step
result = coro.send(None)
File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 319, in recv
raise ConnectionClosed(self.close_code, self.close_reason)
websockets.exceptions.ConnectionClosed: WebSocket connection is closed: code = 1006, no reason.
0|brook |
The above exception was the direct cause of the following exception:
0|brook |
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step
result = coro.send(None)
File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws
yield from self.ws.poll_event()
File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 679, in poll_event
raise ConnectionClosed(e) from e
discord.errors.ConnectionClosed: WebSocket connection is closed: code = 1006, no reason.
Task exception was never retrieved
future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=TimeoutError()>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step
result = coro.send(None)
File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws
yield from self.ws.poll_event()
File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event
msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop)
File "/usr/lib/python3.5/asyncio/tasks.py", line 396, in wait_for
raise futures.TimeoutError()
concurrent.futures._base.TimeoutError
ERROR: Unsupported URL: https://open.spotify.com/user/1245911056/playlist/7nHduZqtw0vd2hwbUuGzDU?si=cn1c6s_tTWCgnNeCdJFDwQ
[mp3 @ 0x563d05ed23c0] Estimating duration from bitrate, this may be inaccurate
Task exception was never retrieved
future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')>
Traceback (most recent call last):
File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step
Steps to reproduce
Log file
Please attach your MusicBot log file (located at logs/musicbot.log) to this issue. You can do so by dragging and dropping the file here. If you do not include your log file, you WILL be asked to provide one.
[125.491380692] 2018-08-02 09:28:46,067 - INFO - launcher: Starting sanity checks
[125.680446625] 2018-08-02 09:28:46,067 - INFO - launcher: Checking for Python 3.5+
[125.760316849] 2018-08-02 09:28:46,067 - INFO - launcher: Checking console encoding
[125.832080841] 2018-08-02 09:28:46,067 - INFO - launcher: Ensuring we're in the right environment
[142.335176468] 2018-08-02 09:28:46,083 - INFO - launcher: Required checks passed.
[142.532825470] 2018-08-02 09:28:46,084 - INFO - launcher: Optional checks passed.
[150.942325592] 2018-08-02 09:28:46,092 - INFO - launcher: Moving old musicbot log
[2.6713960170745850] 2018-08-02 09:28:48,613 - WARNING - musicbot.config | In config.py::MainThread(139748155930368), line 112 in run_checks: i18n file does not exist. Trying to fallback to config/i18n/en.json.
[2.6715958118438721] 2018-08-02 09:28:48,613 - INFO - musicbot.config | In config.py::MainThread(139748155930368), line 123 in run_checks: Using i18n: config/i18n/en.json
[2.6737720966339111] 2018-08-02 09:28:48,615 - DEBUG - musicbot.json | In json.py::MainThread(139748155930368), line 8 in init: Init JSON obj with config/i18n/en.json
[2.7522354125976562] 2018-08-02 09:28:48,693 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 227 in _setup_logging: Set logging level to INFO
[2.7523834705352783] 2018-08-02 09:28:48,694 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 84 in init: Starting MusicBot 1.9.7
[2.7525362968444824] 2018-08-02 09:28:48,694 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 90 in init: Loaded autoplaylist with 1547 entries
[2.8906731605529785] 2018-08-02 09:28:48,832 - DEBUG - musicbot.spotify | In spotify.py::MainThread(139748155930368), line 70 in get_token: Created a new access token: {'token_type': 'Bearer', 'scope': '', 'expires_in': 3600, 'access_token': 'BQCdegvoDT7811Ngg5E4PeOrlhnZenPQoupVns-jQ_zApm3azFdG5US9oYycdf2yeT2PN0gHYMoCtGX1TH4', 'expires_at': 1533205728}
[2.8909053802490234] 2018-08-02 09:28:48,832 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 115 in init: Authenticated with Spotify successfully using client ID and secret.
[14.9929213523864746] 2018-08-02 09:29:00,934 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 1074 in on_ready: Connection established, ready to go.
[14.9930667877197266] 2018-08-02 09:29:00,934 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 339 in _cache_app_info: Caching app info
[15.0359423160552979] 2018-08-02 09:29:00,977 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 889 in _scheck_ensure_env: Ensuring data folders exist
[15.0369892120361328] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 899 in _scheck_ensure_env: Deleted old audio cache
[15.0370883941650391] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 905 in _scheck_server_permissions: Checking server permissions
[15.0371575355529785] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 909 in _scheck_autoplaylist: Auditing autoplaylist
[15.0372166633605957] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 913 in _scheck_configs: Validating config
[15.0372726917266846] 2018-08-02 09:29:00,978 - DEBUG - musicbot.config | In config.py::MainThread(139748155930368), line 212 in async_validate: Validating options...
[15.0373327732086182] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 916 in _scheck_configs: Validating permissions config
[15.0373880863189697] 2018-08-02 09:29:00,979 - DEBUG - musicbot.permissions | In permissions.py::MainThread(139748155930368), line 64 in async_validate: Validating permissions...
[15.0374701023101807] 2018-08-02 09:29:00,979 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1091 in on_ready: Connected: 450906078448844800/brook#8810
[15.0452291965484619] 2018-08-02 09:29:00,986 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1099 in on_ready: Owner: 450353275615576104/art#1111
[15.0454397201538086] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1102 in on_ready: Server List:
[15.0455555915832520] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1105 in on_ready: - Suite 7A
[15.0456674098968506] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1151 in on_ready: Not bound to any text channels
[15.0457890033721924] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1164 in on_ready: Autojoining voice chanels:
[15.0458905696868896] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1165 in : - Suite 7A/music ➋ ♫
[15.0460679531097412] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 281 in _join_startup_channels: Attempting to join Suite 7A/music ➋ ♫
[15.0462725162506104] 2018-08-02 09:29:00,987 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 452 in get_voice_client: Connection attempt 1 to music ➋ ♫
[15.0463535785675049] 2018-08-02 09:29:00,987 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 391 in join_voice_channel: (join_voice_channel) creating futures
[15.0466806888580322] 2018-08-02 09:29:00,988 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 397 in join_voice_channel: (join_voice_channel) setting voice state
[15.0469391345977783] 2018-08-02 09:29:00,988 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 400 in join_voice_channel: (join_voice_channel) waiting for session id
[15.0821936130523682] 2018-08-02 09:29:01,023 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 450906078448844800/brook#8810 on Suite 7A/music ➋ ♫ -> [JOIN, CONNECT]
[15.0823740959167480] 2018-08-02 09:29:01,024 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 2949 in on_voice_state_update: 450906078448844800/brook#8810 has joined Suite 7A/music ➋ ♫
[15.0826227664947510] 2018-08-02 09:29:01,024 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 404 in join_voice_channel: (join_voice_channel) waiting for voice data
[15.1295242309570312] 2018-08-02 09:29:01,071 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 450906209071923212/isabel#0140 on Suite 7A/music ➌ ♫ -> [CONNECT]
[15.1837906837463379] 2018-08-02 09:29:01,125 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 418 in join_voice_channel: (join_voice_channel) connecting...
[15.7145719528198242] 2018-08-02 09:29:01,656 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 430 in join_voice_channel: (join_voice_channel) connection successful
[15.7148673534393311] 2018-08-02 09:29:01,656 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 478 in get_voice_client: Connected in 0.7s
[15.7150046825408936] 2018-08-02 09:29:01,656 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 479 in get_voice_client: Connected to Suite 7A/music ➋ ♫
[15.7156994342803955] 2018-08-02 09:29:01,657 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 849 in deserialize_queue: Deserializing queue for 334487813129109504
[15.9365968704223633] 2018-08-02 09:29:01,878 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 570 in get_player: Created player via deserialization for server 334487813129109504 with 1 entries
[15.9368431568145752] 2018-08-02 09:29:01,878 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 297 in _join_startup_channels: Joined Suite 7A/music ➋ ♫
[15.9375536441802979] 2018-08-02 09:29:01,879 - VOICEDEBUG - musicbot.player | In player.py::MainThread(139748155930368), line 373 in websocket_check: Starting websocket check loop for Suite 7A
[15.9379382133483887] 2018-08-02 09:29:01,879 - DEBUG - musicbot.entry | In entry.py::MainThread(139748155930368), line 54 in get_ready_future: Created future for None
[15.9382152557373047] 2018-08-02 09:29:01,879 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 220 in _really_download: Download started: https://youtube.com/watch?v=TiqG3oOCaeY
[18.8169345855712891] 2018-08-02 09:29:04,758 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 230 in _really_download: Download complete: https://youtube.com/watch?v=TiqG3oOCaeY
[18.8211865425109863] 2018-08-02 09:29:04,762 - FFMPEG - musicbot.player | In player.py::MainThread(139748155930368), line 330 in _play: Creating player with options: -nostdin -vn audio_cache/youtube-TiqG3oOCaeY-Sach_Te_Supna_Full_Song_Nobita_Version_Amrit_Maan_Gag_Studioz_Shree_Raj_Music.m4a
[18.8390300273895264] 2018-08-02 09:29:04,780 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 256 in _autopause: Initial autopause in empty channel
[18.8401136398315430] 2018-08-02 09:29:04,781 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 825 in serialize_queue: Serializing queue for 334487813129109504
[64.3579652309417725] 2018-08-02 09:29:50,299 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [DEAFEN]
[129.3528597354888916] 2018-08-02 09:30:55,294 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 2734 in on_message: 450353275615576104/art#1111: #clear
[132.0064547061920166] 2018-08-02 09:30:57,948 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 2734 in on_message: 450353275615576104/art#1111: #skip f
[132.0474972724914551] 2018-08-02 09:30:57,989 - DEBUG - musicbot.player | In player.py::MainThread(139748155930368), line 202 in _playback_finished: Deleting file: audio_cache/youtube-TiqG3oOCaeY-Sach_Te_Supna_Full_Song_Nobita_Version_Amrit_Maan_Gag_Studioz_Shree_Raj_Music.m4a
[132.0478858947753906] 2018-08-02 09:30:57,989 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 679 in on_player_finished_playing: No content in current autoplaylist. Filling with new music...
[133.6784579753875732] 2018-08-02 09:30:59,620 - DEBUG - musicbot.entry | In entry.py::MainThread(139748155930368), line 54 in get_ready_future: Created future for None
[133.6788647174835205] 2018-08-02 09:30:59,620 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 220 in _really_download: Download started: https://youtube.com/watch?v=G1-Zl6PGjKc
[134.5169062614440918] 2018-08-02 09:31:00,458 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 230 in _really_download: Download complete: https://youtube.com/watch?v=G1-Zl6PGjKc
[135.6797952651977539] 2018-08-02 09:31:01,621 - DEBUG - musicbot.entry | In entry.py::MainThread(139748155930368), line 54 in get_ready_future: Created future for audio_cache/youtube-G1-Zl6PGjKc-KateBoy-_The_Way_We_Are_Official_Music_Video.webm
[135.6800103187561035] 2018-08-02 09:31:01,621 - FFMPEG - musicbot.player | In player.py::MainThread(139748155930368), line 330 in _play: Creating player with options: -nostdin -vn audio_cache/youtube-G1-Zl6PGjKc-KateBoy-_The_Way_We_Are_Official_Music_Video.webm
[135.6846797466278076] 2018-08-02 09:31:01,626 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 666 in _autopause: Player finished playing, autopaused in empty channel
[135.6857643127441406] 2018-08-02 09:31:01,627 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 825 in serialize_queue: Serializing queue for 334487813129109504
[260.2301208972930908] 2018-08-02 09:33:06,171 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/idle ⌢ -> [MOVE, MUTE]
[260.6302142143249512] 2018-08-02 09:33:06,571 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[260.8333508968353271] 2018-08-02 09:33:06,774 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/idle ⌢ -> [MUTE]
[274.9488778114318848] 2018-08-02 09:33:20,890 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [MOVE, UNMUTE]
[275.4010417461395264] 2018-08-02 09:33:21,342 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[291.8922040462493896] 2018-08-02 09:33:37,833 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/idle ⌢ -> [MOVE, MUTE]
[292.1192305088043213] 2018-08-02 09:33:38,060 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[298.5397856235504150] 2018-08-02 09:33:44,481 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [MOVE, UNMUTE]
[298.8664596080780029] 2018-08-02 09:33:44,808 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[315.6910688877105713] 2018-08-02 09:34:01,632 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 242510893110394890/Lordy#0001 on Suite 7A/main ⚑ -> [DISCONNECT]
[315.8140716552734375] 2018-08-02 09:34:01,755 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [UNMUTE]
[327.4750115871429443] 2018-08-02 09:34:13,416 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [MUTE]
[330.2257771492004395] 2018-08-02 09:34:16,167 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DEAFEN]
[369.9719831943511963] 2018-08-02 09:34:55,913 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [MUTE]
[371.7029397487640381] 2018-08-02 09:34:57,644 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [DEAFEN]
[432.7973117828369141] 2018-08-02 09:35:58,738 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [UNDEAFEN]
[435.0562109947204590] 2018-08-02 09:36:00,997 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [UNMUTE]
[437.8360204696655273] 2018-08-02 09:36:03,777 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 242510893110394890/Lordy#0001 on Suite 7A/main ⚑ -> [CONNECT]
[441.8234877586364746] 2018-08-02 09:36:07,765 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 267361551479078912/lmao what#2899 on Suite 7A/main ⚑ -> [DISCONNECT]
[463.5900909900665283] 2018-08-02 09:36:29,531 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 242510893110394890/Lordy#0001 on Suite 7A/main ⚑ -> [DISCONNECT]
[520.1084382534027100] 2018-08-02 09:37:26,050 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 326253301844934658/Slug#1995 on Suite 7A/main ⚑ -> [CONNECT]
[531.7448360919952393] 2018-08-02 09:37:37,686 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 320450172905717762/Moo 🍰#4220 on Suite 7A/main ⚑ -> [CONNECT]
[592.0722739696502686] 2018-08-02 09:38:38,013 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNDEAFEN]
[592.8563239574432373] 2018-08-02 09:38:38,797 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNMUTE]
[696.9237458705902100] 2018-08-02 09:40:22,865 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 291742005166342145/Shi#6943 on Suite 7A/game ༶ -> [CONNECT]
[848.6891310214996338] 2018-08-02 09:42:54,630 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 291742005166342145/Shi#6943 on Suite 7A/game ༶ -> [MUTE]
[862.6876571178436279] 2018-08-02 09:43:08,629 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 291742005166342145/Shi#6943 on Suite 7A/game ༶ -> [UNMUTE]
[1085.7303047180175781] 2018-08-02 09:46:51,671 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [MUTE]
[1086.1419663429260254] 2018-08-02 09:46:52,083 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DEAFEN]
[1096.4797148704528809] 2018-08-02 09:47:02,421 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNMUTE]
[1096.9223256111145020] 2018-08-02 09:47:02,863 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNDEAFEN]
[1120.5148563385009766] 2018-08-02 09:47:26,456 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 320450172905717762/Moo 🍰#4220 on Suite 7A/main ⚑ -> [MUTE]
[1197.0486958026885986] 2018-08-02 09:48:42,990 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 106214338662010880/Shadydragon#7261 on Suite 7A/main ⚑ -> [CONNECT]
[1216.4410393238067627] 2018-08-02 09:49:02,382 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [UNDEAFEN]
[1217.1631627082824707] 2018-08-02 09:49:03,104 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [UNMUTE]
[1247.9873831272125244] 2018-08-02 09:49:33,929 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 320450172905717762/Moo 🍰#4220 on Suite 7A/main ⚑ -> [UNMUTE]
[1284.5306196212768555] 2018-08-02 09:50:10,472 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [MUTE]
[1321.3965013027191162] 2018-08-02 09:50:47,338 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [MUTE]
[1321.7139165401458740] 2018-08-02 09:50:47,655 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[1322.9715459346771240] 2018-08-02 09:50:48,913 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [UNMUTE]
[1411.0472795963287354] 2018-08-02 09:52:16,988 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 261139637785853953/Thorn#9051 on Suite 7A/main ⚑ -> [CONNECT]
[1461.1944208145141602] 2018-08-02 09:53:07,136 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[1605.0348615646362305] 2018-08-02 09:55:30,976 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [MUTE]
[1969.3480217456817627] 2018-08-02 10:01:35,289 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [UNMUTE]
[2008.7103135585784912] 2018-08-02 10:02:14,651 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [DISCONNECT]
[2315.4690701961517334] 2018-08-02 10:07:21,410 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DISCONNECT]
[2319.8651254177093506] 2018-08-02 10:07:25,806 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [CONNECT]
[2348.2558116912841797] 2018-08-02 10:07:54,197 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DEAFEN]
[2374.2307543754577637] 2018-08-02 10:08:20,172 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [DISCONNECT]
Please tick all applicable boxes.
python --version
on the command line)update
scriptWhich version are you using?
master
version (1.9.7)review
versionWhat type of issue are you creating?
Description of issue
WARNING: i18n file does not exist. Trying to fallback to config/i18n/en.json.
Fatal read error on socket transport protocol: <asyncio.sslproto.SSLProtocol object at 0x7f672e38bbe0> transport: <_SelectorSocketTransport fd=11 read=polling write=<idle, bufsize=0>> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready data = self._sock.recv(self.max_size) TimeoutError: [Errno 110] Connection timed out ERROR: This video is unavailable. Task exception was never retrieved future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=TimeoutError()> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step result = coro.send(None) File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws yield from self.ws.poll_event() File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop) File "/usr/lib/python3.5/asyncio/tasks.py", line 396, in wait_for raise futures.TimeoutError() concurrent.futures._base.TimeoutError Task exception was never retrieved future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=TimeoutError()> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step result = coro.send(None) File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws yield from self.ws.poll_event() File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop) File "/usr/lib/python3.5/asyncio/tasks.py", line 396, in wait_for raise futures.TimeoutError() concurrent.futures._base.TimeoutError Task exception was never retrieved future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step result = coro.throw(exc) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 434, in run msg = yield from self.read_message() File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 456, in read_message frame = yield from self.read_data_frame(max_size=self.max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 511, in read_data_frame frame = yield from self.read_frame(max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 546, in read_frame self.reader.readexactly, is_masked, max_size=max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/framing.py", line 86, in read_frame data = yield from reader(2) File "/usr/lib/python3.5/asyncio/streams.py", line 670, in readexactly block = yield from self.read(n) File "/usr/lib/python3.5/asyncio/streams.py", line 627, in read yield from self._wait_for_data('read') File "/usr/lib/python3.5/asyncio/streams.py", line 457, in _wait_for_data yield from self._waiter File "/usr/lib/python3.5/asyncio/futures.py", line 361, in iter yield self # This tells Task to wait for completion. File "/usr/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup future.result() File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result raise self._exception File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready data = self._sock.recv(self.max_size) ConnectionResetError: [Errno 104] Connection reset by peer Task exception was never retrieved future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step result = coro.throw(exc) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 434, in run msg = yield from self.read_message() File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 456, in read_message frame = yield from self.read_data_frame(max_size=self.max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 511, in read_data_frame frame = yield from self.read_frame(max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 546, in read_frame self.reader.readexactly, is_masked, max_size=max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/framing.py", line 86, in read_frame data = yield from reader(2) File "/usr/lib/python3.5/asyncio/streams.py", line 670, in readexactly block = yield from self.read(n) File "/usr/lib/python3.5/asyncio/streams.py", line 627, in read yield from self._wait_for_data('read') File "/usr/lib/python3.5/asyncio/streams.py", line 457, in _wait_for_data yield from self._waiter File "/usr/lib/python3.5/asyncio/futures.py", line 361, in iter yield self # This tells Task to wait for completion. File "/usr/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup future.result() File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result raise self._exception File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready data = self._sock.recv(self.max_size) ConnectionResetError: [Errno 104] Connection reset by peer ERROR: This video contains content from SME, who has blocked it in your country on copyright grounds. Task exception was never retrieved future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step result = coro.throw(exc) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 434, in run msg = yield from self.read_message() File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 456, in read_message frame = yield from self.read_data_frame(max_size=self.max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 511, in read_data_frame frame = yield from self.read_frame(max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 546, in read_frame self.reader.readexactly, is_masked, max_size=max_size) File "/usr/local/lib/python3.5/dist-packages/websockets/framing.py", line 86, in read_frame data = yield from reader(2) File "/usr/lib/python3.5/asyncio/streams.py", line 670, in readexactly block = yield from self.read(n) File "/usr/lib/python3.5/asyncio/streams.py", line 627, in read yield from self._wait_for_data('read') File "/usr/lib/python3.5/asyncio/streams.py", line 457, in _wait_for_data yield from self._waiter File "/usr/lib/python3.5/asyncio/futures.py", line 361, in iter yield self # This tells Task to wait for completion. File "/usr/lib/python3.5/asyncio/tasks.py", line 296, in _wakeup future.result() File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result raise self._exception File "/usr/lib/python3.5/asyncio/selector_events.py", line 662, in _read_ready data = self._sock.recv(self.max_size) ConnectionResetError: [Errno 104] Connection reset by peer ERROR: Unsupported URL: https://open.spotify.com/track/6oxGueIQWk8hmYpStfgGww?si=JSATbtlnQjOO5PWJKDFRgQ Task exception was never retrieved future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=ConnectionClosed('WebSocket connection is closed: code = 1006, no reason.',)> Traceback (most recent call last): File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop) File "/usr/lib/python3.5/asyncio/tasks.py", line 392, in wait_for return fut.result() File "/usr/lib/python3.5/asyncio/futures.py", line 274, in result raise self._exception File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step result = coro.send(None) File "/usr/local/lib/python3.5/dist-packages/websockets/protocol.py", line 319, in recv raise ConnectionClosed(self.close_code, self.close_reason) websockets.exceptions.ConnectionClosed: WebSocket connection is closed: code = 1006, no reason. 0|brook | The above exception was the direct cause of the following exception: 0|brook | Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step result = coro.send(None) File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws yield from self.ws.poll_event() File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 679, in poll_event raise ConnectionClosed(e) from e discord.errors.ConnectionClosed: WebSocket connection is closed: code = 1006, no reason. Task exception was never retrieved future: <Task finished coro=<VoiceClient.poll_voice_ws() done, defined at /usr/local/lib/python3.5/dist-packages/discord/voice_client.py:269> exception=TimeoutError()> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 239, in _step result = coro.send(None) File "/usr/local/lib/python3.5/dist-packages/discord/voice_client.py", line 276, in poll_voice_ws yield from self.ws.poll_event() File "/usr/local/lib/python3.5/dist-packages/discord/gateway.py", line 676, in poll_event msg = yield from asyncio.wait_for(self.recv(), timeout=30.0, loop=self.loop) File "/usr/lib/python3.5/asyncio/tasks.py", line 396, in wait_for raise futures.TimeoutError() concurrent.futures._base.TimeoutError ERROR: Unsupported URL: https://open.spotify.com/user/1245911056/playlist/7nHduZqtw0vd2hwbUuGzDU?si=cn1c6s_tTWCgnNeCdJFDwQ [mp3 @ 0x563d05ed23c0] Estimating duration from bitrate, this may be inaccurate Task exception was never retrieved future: <Task finished coro=<WebSocketCommonProtocol.run() done, defined at /usr/local/lib/python3.5/dist-packages/websockets/protocol.py:428> exception=ConnectionResetError(104, 'Connection reset by peer')> Traceback (most recent call last): File "/usr/lib/python3.5/asyncio/tasks.py", line 241, in _step
Steps to reproduce
Log file
Please attach your MusicBot log file (located at
logs/musicbot.log
) to this issue. You can do so by dragging and dropping the file here. If you do not include your log file, you WILL be asked to provide one. [125.491380692] 2018-08-02 09:28:46,067 - INFO - launcher: Starting sanity checks [125.680446625] 2018-08-02 09:28:46,067 - INFO - launcher: Checking for Python 3.5+ [125.760316849] 2018-08-02 09:28:46,067 - INFO - launcher: Checking console encoding [125.832080841] 2018-08-02 09:28:46,067 - INFO - launcher: Ensuring we're in the right environment [142.335176468] 2018-08-02 09:28:46,083 - INFO - launcher: Required checks passed. [142.532825470] 2018-08-02 09:28:46,084 - INFO - launcher: Optional checks passed. [150.942325592] 2018-08-02 09:28:46,092 - INFO - launcher: Moving old musicbot log######################### PRE-RUN SANITY CHECKS PASSED #########################
[2.6713960170745850] 2018-08-02 09:28:48,613 - WARNING - musicbot.config | In config.py::MainThread(139748155930368), line 112 in run_checks: i18n file does not exist. Trying to fallback to config/i18n/en.json. [2.6715958118438721] 2018-08-02 09:28:48,613 - INFO - musicbot.config | In config.py::MainThread(139748155930368), line 123 in run_checks: Using i18n: config/i18n/en.json [2.6737720966339111] 2018-08-02 09:28:48,615 - DEBUG - musicbot.json | In json.py::MainThread(139748155930368), line 8 in init: Init JSON obj with config/i18n/en.json [2.7522354125976562] 2018-08-02 09:28:48,693 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 227 in _setup_logging: Set logging level to INFO [2.7523834705352783] 2018-08-02 09:28:48,694 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 84 in init: Starting MusicBot 1.9.7 [2.7525362968444824] 2018-08-02 09:28:48,694 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 90 in init: Loaded autoplaylist with 1547 entries [2.8906731605529785] 2018-08-02 09:28:48,832 - DEBUG - musicbot.spotify | In spotify.py::MainThread(139748155930368), line 70 in get_token: Created a new access token: {'token_type': 'Bearer', 'scope': '', 'expires_in': 3600, 'access_token': 'BQCdegvoDT7811Ngg5E4PeOrlhnZenPQoupVns-jQ_zApm3azFdG5US9oYycdf2yeT2PN0gHYMoCtGX1TH4', 'expires_at': 1533205728} [2.8909053802490234] 2018-08-02 09:28:48,832 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 115 in init: Authenticated with Spotify successfully using client ID and secret. [14.9929213523864746] 2018-08-02 09:29:00,934 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 1074 in on_ready: Connection established, ready to go. [14.9930667877197266] 2018-08-02 09:29:00,934 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 339 in _cache_app_info: Caching app info [15.0359423160552979] 2018-08-02 09:29:00,977 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 889 in _scheck_ensure_env: Ensuring data folders exist [15.0369892120361328] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 899 in _scheck_ensure_env: Deleted old audio cache [15.0370883941650391] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 905 in _scheck_server_permissions: Checking server permissions [15.0371575355529785] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 909 in _scheck_autoplaylist: Auditing autoplaylist [15.0372166633605957] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 913 in _scheck_configs: Validating config [15.0372726917266846] 2018-08-02 09:29:00,978 - DEBUG - musicbot.config | In config.py::MainThread(139748155930368), line 212 in async_validate: Validating options... [15.0373327732086182] 2018-08-02 09:29:00,978 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 916 in _scheck_configs: Validating permissions config [15.0373880863189697] 2018-08-02 09:29:00,979 - DEBUG - musicbot.permissions | In permissions.py::MainThread(139748155930368), line 64 in async_validate: Validating permissions... [15.0374701023101807] 2018-08-02 09:29:00,979 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1091 in on_ready: Connected: 450906078448844800/brook#8810 [15.0452291965484619] 2018-08-02 09:29:00,986 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1099 in on_ready: Owner: 450353275615576104/art#1111
[15.0454397201538086] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1102 in on_ready: Server List: [15.0455555915832520] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1105 in on_ready: - Suite 7A [15.0456674098968506] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1151 in on_ready: Not bound to any text channels [15.0457890033721924] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1164 in on_ready: Autojoining voice chanels: [15.0458905696868896] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 1165 in: - Suite 7A/music ➋ ♫
[15.0460679531097412] 2018-08-02 09:29:00,987 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 281 in _join_startup_channels: Attempting to join Suite 7A/music ➋ ♫
[15.0462725162506104] 2018-08-02 09:29:00,987 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 452 in get_voice_client: Connection attempt 1 to music ➋ ♫
[15.0463535785675049] 2018-08-02 09:29:00,987 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 391 in join_voice_channel: (join_voice_channel) creating futures
[15.0466806888580322] 2018-08-02 09:29:00,988 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 397 in join_voice_channel: (join_voice_channel) setting voice state
[15.0469391345977783] 2018-08-02 09:29:00,988 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 400 in join_voice_channel: (join_voice_channel) waiting for session id
[15.0821936130523682] 2018-08-02 09:29:01,023 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 450906078448844800/brook#8810 on Suite 7A/music ➋ ♫ -> [JOIN, CONNECT]
[15.0823740959167480] 2018-08-02 09:29:01,024 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 2949 in on_voice_state_update: 450906078448844800/brook#8810 has joined Suite 7A/music ➋ ♫
[15.0826227664947510] 2018-08-02 09:29:01,024 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 404 in join_voice_channel: (join_voice_channel) waiting for voice data
[15.1295242309570312] 2018-08-02 09:29:01,071 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 450906209071923212/isabel#0140 on Suite 7A/music ➌ ♫ -> [CONNECT]
[15.1837906837463379] 2018-08-02 09:29:01,125 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 418 in join_voice_channel: (join_voice_channel) connecting...
[15.7145719528198242] 2018-08-02 09:29:01,656 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 430 in join_voice_channel: (join_voice_channel) connection successful
[15.7148673534393311] 2018-08-02 09:29:01,656 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 478 in get_voice_client: Connected in 0.7s
[15.7150046825408936] 2018-08-02 09:29:01,656 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 479 in get_voice_client: Connected to Suite 7A/music ➋ ♫
[15.7156994342803955] 2018-08-02 09:29:01,657 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 849 in deserialize_queue: Deserializing queue for 334487813129109504
[15.9365968704223633] 2018-08-02 09:29:01,878 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 570 in get_player: Created player via deserialization for server 334487813129109504 with 1 entries
[15.9368431568145752] 2018-08-02 09:29:01,878 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 297 in _join_startup_channels: Joined Suite 7A/music ➋ ♫
[15.9375536441802979] 2018-08-02 09:29:01,879 - VOICEDEBUG - musicbot.player | In player.py::MainThread(139748155930368), line 373 in websocket_check: Starting websocket check loop for Suite 7A
[15.9379382133483887] 2018-08-02 09:29:01,879 - DEBUG - musicbot.entry | In entry.py::MainThread(139748155930368), line 54 in get_ready_future: Created future for None
[15.9382152557373047] 2018-08-02 09:29:01,879 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 220 in _really_download: Download started: https://youtube.com/watch?v=TiqG3oOCaeY
[18.8169345855712891] 2018-08-02 09:29:04,758 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 230 in _really_download: Download complete: https://youtube.com/watch?v=TiqG3oOCaeY
[18.8211865425109863] 2018-08-02 09:29:04,762 - FFMPEG - musicbot.player | In player.py::MainThread(139748155930368), line 330 in _play: Creating player with options: -nostdin -vn audio_cache/youtube-TiqG3oOCaeY-Sach_Te_Supna_Full_Song_Nobita_Version_Amrit_Maan_Gag_Studioz_Shree_Raj_Music.m4a
[18.8390300273895264] 2018-08-02 09:29:04,780 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 256 in _autopause: Initial autopause in empty channel
[18.8401136398315430] 2018-08-02 09:29:04,781 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 825 in serialize_queue: Serializing queue for 334487813129109504
[64.3579652309417725] 2018-08-02 09:29:50,299 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [DEAFEN]
[129.3528597354888916] 2018-08-02 09:30:55,294 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 2734 in on_message: 450353275615576104/art#1111: #clear
[132.0064547061920166] 2018-08-02 09:30:57,948 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 2734 in on_message: 450353275615576104/art#1111: #skip f
[132.0474972724914551] 2018-08-02 09:30:57,989 - DEBUG - musicbot.player | In player.py::MainThread(139748155930368), line 202 in _playback_finished: Deleting file: audio_cache/youtube-TiqG3oOCaeY-Sach_Te_Supna_Full_Song_Nobita_Version_Amrit_Maan_Gag_Studioz_Shree_Raj_Music.m4a
[132.0478858947753906] 2018-08-02 09:30:57,989 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 679 in on_player_finished_playing: No content in current autoplaylist. Filling with new music...
[133.6784579753875732] 2018-08-02 09:30:59,620 - DEBUG - musicbot.entry | In entry.py::MainThread(139748155930368), line 54 in get_ready_future: Created future for None
[133.6788647174835205] 2018-08-02 09:30:59,620 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 220 in _really_download: Download started: https://youtube.com/watch?v=G1-Zl6PGjKc
[134.5169062614440918] 2018-08-02 09:31:00,458 - INFO - musicbot.entry | In entry.py::MainThread(139748155930368), line 230 in _really_download: Download complete: https://youtube.com/watch?v=G1-Zl6PGjKc
[135.6797952651977539] 2018-08-02 09:31:01,621 - DEBUG - musicbot.entry | In entry.py::MainThread(139748155930368), line 54 in get_ready_future: Created future for audio_cache/youtube-G1-Zl6PGjKc-KateBoy-_The_Way_We_Are_Official_Music_Video.webm
[135.6800103187561035] 2018-08-02 09:31:01,621 - FFMPEG - musicbot.player | In player.py::MainThread(139748155930368), line 330 in _play: Creating player with options: -nostdin -vn audio_cache/youtube-G1-Zl6PGjKc-KateBoy-_The_Way_We_Are_Official_Music_Video.webm
[135.6846797466278076] 2018-08-02 09:31:01,626 - INFO - musicbot.bot | In bot.py::MainThread(139748155930368), line 666 in _autopause: Player finished playing, autopaused in empty channel
[135.6857643127441406] 2018-08-02 09:31:01,627 - DEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 825 in serialize_queue: Serializing queue for 334487813129109504
[260.2301208972930908] 2018-08-02 09:33:06,171 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/idle ⌢ -> [MOVE, MUTE]
[260.6302142143249512] 2018-08-02 09:33:06,571 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[260.8333508968353271] 2018-08-02 09:33:06,774 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/idle ⌢ -> [MUTE]
[274.9488778114318848] 2018-08-02 09:33:20,890 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [MOVE, UNMUTE]
[275.4010417461395264] 2018-08-02 09:33:21,342 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[291.8922040462493896] 2018-08-02 09:33:37,833 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/idle ⌢ -> [MOVE, MUTE]
[292.1192305088043213] 2018-08-02 09:33:38,060 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[298.5397856235504150] 2018-08-02 09:33:44,481 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [MOVE, UNMUTE]
[298.8664596080780029] 2018-08-02 09:33:44,808 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[315.6910688877105713] 2018-08-02 09:34:01,632 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 242510893110394890/Lordy#0001 on Suite 7A/main ⚑ -> [DISCONNECT]
[315.8140716552734375] 2018-08-02 09:34:01,755 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [UNMUTE]
[327.4750115871429443] 2018-08-02 09:34:13,416 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [MUTE]
[330.2257771492004395] 2018-08-02 09:34:16,167 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DEAFEN]
[369.9719831943511963] 2018-08-02 09:34:55,913 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [MUTE]
[371.7029397487640381] 2018-08-02 09:34:57,644 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [DEAFEN]
[432.7973117828369141] 2018-08-02 09:35:58,738 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [UNDEAFEN]
[435.0562109947204590] 2018-08-02 09:36:00,997 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 80642916950749184/Char#4312 on Suite 7A/main ⚑ -> [UNMUTE]
[437.8360204696655273] 2018-08-02 09:36:03,777 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 242510893110394890/Lordy#0001 on Suite 7A/main ⚑ -> [CONNECT]
[441.8234877586364746] 2018-08-02 09:36:07,765 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 267361551479078912/lmao what#2899 on Suite 7A/main ⚑ -> [DISCONNECT]
[463.5900909900665283] 2018-08-02 09:36:29,531 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 242510893110394890/Lordy#0001 on Suite 7A/main ⚑ -> [DISCONNECT]
[520.1084382534027100] 2018-08-02 09:37:26,050 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 326253301844934658/Slug#1995 on Suite 7A/main ⚑ -> [CONNECT]
[531.7448360919952393] 2018-08-02 09:37:37,686 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 320450172905717762/Moo 🍰#4220 on Suite 7A/main ⚑ -> [CONNECT]
[592.0722739696502686] 2018-08-02 09:38:38,013 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNDEAFEN]
[592.8563239574432373] 2018-08-02 09:38:38,797 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNMUTE]
[696.9237458705902100] 2018-08-02 09:40:22,865 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 291742005166342145/Shi#6943 on Suite 7A/game ༶ -> [CONNECT]
[848.6891310214996338] 2018-08-02 09:42:54,630 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 291742005166342145/Shi#6943 on Suite 7A/game ༶ -> [MUTE]
[862.6876571178436279] 2018-08-02 09:43:08,629 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 291742005166342145/Shi#6943 on Suite 7A/game ༶ -> [UNMUTE]
[1085.7303047180175781] 2018-08-02 09:46:51,671 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [MUTE]
[1086.1419663429260254] 2018-08-02 09:46:52,083 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DEAFEN]
[1096.4797148704528809] 2018-08-02 09:47:02,421 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNMUTE]
[1096.9223256111145020] 2018-08-02 09:47:02,863 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [UNDEAFEN]
[1120.5148563385009766] 2018-08-02 09:47:26,456 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 320450172905717762/Moo 🍰#4220 on Suite 7A/main ⚑ -> [MUTE]
[1197.0486958026885986] 2018-08-02 09:48:42,990 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 106214338662010880/Shadydragon#7261 on Suite 7A/main ⚑ -> [CONNECT]
[1216.4410393238067627] 2018-08-02 09:49:02,382 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [UNDEAFEN]
[1217.1631627082824707] 2018-08-02 09:49:03,104 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [UNMUTE]
[1247.9873831272125244] 2018-08-02 09:49:33,929 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 320450172905717762/Moo 🍰#4220 on Suite 7A/main ⚑ -> [UNMUTE]
[1284.5306196212768555] 2018-08-02 09:50:10,472 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [MUTE]
[1321.3965013027191162] 2018-08-02 09:50:47,338 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [MUTE]
[1321.7139165401458740] 2018-08-02 09:50:47,655 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[1322.9715459346771240] 2018-08-02 09:50:48,913 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [UNMUTE]
[1411.0472795963287354] 2018-08-02 09:52:16,988 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 261139637785853953/Thorn#9051 on Suite 7A/main ⚑ -> [CONNECT]
[1461.1944208145141602] 2018-08-02 09:53:07,136 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2929 in on_voice_state_update: Empty voice state update, likely a session id change
[1605.0348615646362305] 2018-08-02 09:55:30,976 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [MUTE]
[1969.3480217456817627] 2018-08-02 10:01:35,289 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [UNMUTE]
[2008.7103135585784912] 2018-08-02 10:02:14,651 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 260492473250480128/Viv#6158 on Suite 7A/game ༶ -> [DISCONNECT]
[2315.4690701961517334] 2018-08-02 10:07:21,410 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DISCONNECT]
[2319.8651254177093506] 2018-08-02 10:07:25,806 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [CONNECT]
[2348.2558116912841797] 2018-08-02 10:07:54,197 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 229896487881015296/somebody#4777 on Suite 7A/main ⚑ -> [DEAFEN]
[2374.2307543754577637] 2018-08-02 10:08:20,172 - VOICEDEBUG - musicbot.bot | In bot.py::MainThread(139748155930368), line 2938 in on_voice_state_update: Voice state update for 314176841751724032/Zerohero8942#8952 on Suite 7A/main ⚑ -> [DISCONNECT]