Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Bot is not ready or author not in voice channel, please try again later. #27

Closed
HeckleCode opened this issue Aug 31, 2024 · 4 comments
Closed
Assignees
Labels
question Further information is requested

Comments

@HeckleCode
Copy link

I'm trying to use this bot, but I keep getting the same message.
The logs seem to indicate that it's correctly connected to abs, and the autocomplete and suggestions appear to work correctly.

2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,311 : Initializing wishlist table
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,325 : Initializing tasks table
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Bot is Starting Up! | Startup Time: 2024-08-31 10:01:17.346304-04:00
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Starting up bookshelf traveller v.V1.2.6
2024-08-31 15:01:17 WARNING - 2024-08-31 14:01:17,346 : Please wait for this process to finish prior to use, you have been warned!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Current config to follow!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : VERSION: V1.2.6
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : SERVER_URL: http://xxx.xxx.xxx.xxx:13378
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : TIMEZONE: America/Toronto
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : PLAYBACK_ROLE: 0
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : OWNER_ONLY: False
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : TASK_FREQUENCY: 5
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : AUDIO_ENABLED: True
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : MULTI_USER: True
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Testing Server Connection
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,356 : Connection Established!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,356 : Current Server Status = 200, Good to go!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,357 : Running client sanity checks...
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,357 : Providing Auth Token to Server
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,899 : Cleaning up, authentication
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,899 : Logging user in and verifying role.
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,899 : ABS user logged in as ADMIN with type: root
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,900 : Default Commands loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,904 : No setup function found in default_commands
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,904 : Found extension class PrimaryCommands in default_commands: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,905 : Added callback: PrimaryCommands.all_library_items
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.ping
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.search_book
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.search_media_progress
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.setup_default_commands
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.show_all_libraries
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.show_recent_sessions
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.totalTime
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,907 : Loaded Extension: default_commands
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,907 : Context Menus module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,907 : No setup function found in context-menus
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Found extension class ContextMenus in context-menus: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Added callback: ContextMenus.repeat
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Loaded Extension: context-menus
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,908 : Subscribable Task module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : No setup function found in subscription_task
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Found extension class SubscriptionTask in subscription_task: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Added callback: SubscriptionTask.active_tasks_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Added callback: SubscriptionTask.newBookCheck
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: SubscriptionTask.remove_task_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: SubscriptionTask.task_setup
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Loaded Extension: subscription_task
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,909 : Wishlist module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : No setup function found in wishlist
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Found extension class WishList in wishlist: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: WishList.add_book_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: WishList.cancel_button_callback
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: WishList.on_search_menu_select
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.remove_book_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.request_button_callback
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.view_all_wishlists
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.view_wishlist
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Loaded Extension: wishlist
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,910 : Audio module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,917 : No setup function found in audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Found extension class AudioPlayBack in audio: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_forward_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_next_chapter_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_pause_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_play_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_previous_chapter_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_rewind_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_stop_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_volume_down_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_volume_up_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.change_chapter
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.close_active_sessions
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.pause_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.play_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.refresh_play_card
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.resume_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.stop_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.volume_adjuster
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Loaded Extension: audio
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,919 : MULTI_USER module loaded!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,922 : Initializing Sqlite DB
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : No setup function found in multi_user
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : Found extension class MultiUser in multi_user: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : Added callback: MultiUser.remove_db_user
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : Added callback: MultiUser.user_check
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Added callback: MultiUser.user_login
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Added callback: MultiUser.user_select_db
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Loaded Extension: multi_user
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Altering default database columns
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Error occured while attempting to alter original databases
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : duplicate column name: downloaded
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_startup
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : 1 callbacks have been loaded from __main__.
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: _disconnect
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: _on_websocket_ready
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_autocomplete_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_autocomplete_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_command_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_command_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_component_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_component_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_modal_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : Added callback: on_modal_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : Added callback: on_resume
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : 12 callbacks have been loaded from Client.
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : Attempting to login
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,164 : Caching ingested rate limit data for: 78bb8553d9352a5a2f89f9def401287a
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,164 : GET /users/@me Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,456 : Caching ingested rate limit data for: d28a133af187e91d71d96a223467ce49
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,456 : GET /oauth2/applications/@me Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,508 : Caching ingested rate limit data for: 0a6d458104b103c7be293cc9b284d9ee
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,508 : GET /gateway Received 200 :: [99/100 calls remaining]
2024-08-31 15:01:18 INFO  - 2024-08-31 14:01:18,508 : Shard ID 0 | Starting Shard
2024-08-31 15:01:18 INFO  - 2024-08-31 14:01:18,508 : Shard ID 0 | Shard is attempting to connect to gateway...
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,672 : Shard ID 0 | Identification payload sent to gateway, requesting intents: 53706493
2024-08-31 15:01:18 INFO  - 2024-08-31 14:01:18,926 : Shard ID 0 | Gateway connection established
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,926 : Shard ID 0 | Session ID: c669219c192957e54ac856943f185764 Trace: ['["gateway-prd-us-east1-d-q0k3",{"micros":146669,"calls":["id_created",{"micros":671,"calls":[]},"session_lookup_time",{"micros":361,"calls":[]},"session_lookup_finished",{"micros":13,"calls":[]},"discord-sessions-prd-1-78",{"micros":142400,"calls":["start_session",{"micros":101061,"calls":["discord-api-rpc-6d68cf98f7-dvvdg",{"micros":89233,"calls":["get_user",{"micros":18047},"get_guilds",{"micros":20485},"send_scheduled_deletion_message",{"micros":7},"guild_join_requests",{"micros":2},"authorized_ip_coro",{"micros":6}]}]},"starting_guild_connect",{"micros":62,"calls":[]},"presence_started",{"micros":2659,"calls":[]},"guilds_started",{"micros":1,"calls":[]},"guilds_connect",{"micros":1,"calls":[]},"presence_connect",{"micros":38595,"calls":[]},"connect_finished",{"micros":38600,"calls":[]},"build_ready",{"micros":13,"calls":[]},"clean_ready",{"micros":0,"calls":[]},"optimize_ready",{"micros":1,"calls":[]},"split_ready",{"micros":0,"calls":[]}]}]}]']
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,926 : Dispatching Event: websocket_ready
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,119 : Caching ingested rate limit data for: dd84d9818020c27975c0d5ab4a794d44
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,119 : GET /applications/{application_id}/commands Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,299 : Caching ingested rate limit data for: dd84d9818020c27975c0d5ab4a794d44
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,299 : GET /applications/{application_id}/commands Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,300 : 0 is already up-to-date with 33 commands.
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,300 : Sync of 1 scopes took 0.3742651089996798 seconds
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,300 : Dispatching Event: startup
2024-08-31 15:01:19 WARNING - 2024-08-31 14:01:19,300 : Initialized subscription task module, verifying if any tasks are enabled...
2024-08-31 15:01:19 INFO  - 2024-08-31 14:01:19,317 : Inserted: heckle with token and discord_id
2024-08-31 15:01:19 INFO  - 2024-08-31 14:01:19,317 : Registered initial user heckle successfully
2024-08-31 15:01:19 INFO  - 2024-08-31 14:01:19,317 : Bot has finished loading, it is now safe to use! :)
2024-08-31 15:01:24 DEBUG - 2024-08-31 14:01:24,932 : Sending heartbeat every 41.25 seconds
2024-08-31 15:01:24 DEBUG - 2024-08-31 14:01:24,932 : Sending data to websocket: {"op": 1, "d": 1}
2024-08-31 15:01:24 DEBUG - 2024-08-31 14:01:24,932 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:01:25 DEBUG - 2024-08-31 14:01:25,031 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,415 : 1279397932122247293::play should be called
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,719 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,719 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MTAwODcxNDU4NDE1NzpUcTRxanVsbnp2bHNrT0FwdFpBMEZ0dTFDbmJxbEh2UjlGRHlLaUxXZGtEbHZBQzFyUklJc25xbnVkRmR5cmVhR25kSXNWMGRST29qcmZmUDRvMGt2cEpjalBmRXROSGVTRUtHZWdGbXVPc2RaNDh0UkNlREF5Y3NtVHhpcExhdQ/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:01:47 INFO  - 2024-08-31 14:01:47,719 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,719 : Dispatching Event: autocomplete_completion
2024-08-31 15:01:47 INFO  - 2024-08-31 14:01:47,719 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:01:53 DEBUG - 2024-08-31 14:01:53,648 : 1279397932122247293::play should be called
2024-08-31 15:01:53 DEBUG - 2024-08-31 14:01:53,859 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:01:53 DEBUG - 2024-08-31 14:01:53,859 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MTAzNTE0MzE1MTY1NjoxUUFuczgzWEQzb2xGM01mUWM1R2lFNnN6Q3Nva3pVcDJDMTNyOGdqZVFubmo2UFNDWGVKOW8yUG1HV2podzNBMDFzR1lPUGp3cHp3MVBrazR3ZndEWmtmMER2MHpsZU1KVXZVaTNvQ0VNUnlVVHRLY2tMOFk2enBUTFFVdnJNag/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:01:54 DEBUG - 2024-08-31 14:01:54,074 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:01:54 DEBUG - 2024-08-31 14:01:54,074 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0MTAzNTE0MzE1MTY1NjoxUUFuczgzWEQzb2xGM01mUWM1R2lFNnN6Q3Nva3pVcDJDMTNyOGdqZVFubmo2UFNDWGVKOW8yUG1HV2podzNBMDFzR1lPUGp3cHp3MVBrazR3ZndEWmtmMER2MHpsZU1KVXZVaTNvQ0VNUnlVVHRLY2tMOFk2enBUTFFVdnJNag/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:01:54 DEBUG - 2024-08-31 14:01:54,075 : Dispatching Event: command_completion
2024-08-31 15:01:54 INFO  - 2024-08-31 14:01:54,075 : Command Called: play with event.ctx.args = ['7247c259-b3ad-4fcc-a613-de771eaac71f'] | event.ctx.kwargs = {'book': '7247c259-b3ad-4fcc-a613-de771eaac71f'}
2024-08-31 15:02:06 DEBUG - 2024-08-31 14:02:06,184 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:02:06 DEBUG - 2024-08-31 14:02:06,185 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:02:06 DEBUG - 2024-08-31 14:02:06,281 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:02:47 DEBUG - 2024-08-31 14:02:47,456 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:02:47 DEBUG - 2024-08-31 14:02:47,457 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:02:47 DEBUG - 2024-08-31 14:02:47,564 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:03:28 DEBUG - 2024-08-31 14:03:28,749 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:03:28 DEBUG - 2024-08-31 14:03:28,749 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:03:28 DEBUG - 2024-08-31 14:03:28,849 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:04:10 DEBUG - 2024-08-31 14:04:10,039 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:04:10 DEBUG - 2024-08-31 14:04:10,040 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:04:10 DEBUG - 2024-08-31 14:04:10,146 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:04:51 DEBUG - 2024-08-31 14:04:51,295 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:04:51 DEBUG - 2024-08-31 14:04:51,295 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:04:51 DEBUG - 2024-08-31 14:04:51,394 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:05:32 DEBUG - 2024-08-31 14:05:32,587 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:05:32 DEBUG - 2024-08-31 14:05:32,588 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:05:32 DEBUG - 2024-08-31 14:05:32,686 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:06:13 DEBUG - 2024-08-31 14:06:13,846 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:06:13 DEBUG - 2024-08-31 14:06:13,846 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:06:13 DEBUG - 2024-08-31 14:06:13,951 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:06:55 DEBUG - 2024-08-31 14:06:55,133 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:06:55 DEBUG - 2024-08-31 14:06:55,134 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:06:55 DEBUG - 2024-08-31 14:06:55,240 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:07:36 DEBUG - 2024-08-31 14:07:36,426 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:07:36 DEBUG - 2024-08-31 14:07:36,426 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:07:36 DEBUG - 2024-08-31 14:07:36,524 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:08:17 DEBUG - 2024-08-31 14:08:17,718 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:08:17 DEBUG - 2024-08-31 14:08:17,718 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:08:17 DEBUG - 2024-08-31 14:08:17,813 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:08:59 DEBUG - 2024-08-31 14:08:59,010 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:08:59 DEBUG - 2024-08-31 14:08:59,010 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:08:59 DEBUG - 2024-08-31 14:08:59,107 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:09:40 DEBUG - 2024-08-31 14:09:40,280 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:09:40 DEBUG - 2024-08-31 14:09:40,280 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:09:40 DEBUG - 2024-08-31 14:09:40,375 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,087 : 1279397932122247293::play should be called
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,332 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,332 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzA5NjMwNzc2NTM3MDo4cjRiU2FoekpxYVZUazZKNUNZUzdTcjg0d3hxYWt0SHFmdFFrUmRZNjdTUzZIYVJFTDdHbnVUQ0V0czhHOHZNY0VJNGt3ZWlsb3dhR3FRSUVhR3A1bTRuNzU0Z3ppVTNVaXlmQ0tMRlU5UUMzMEdiTW8waDB4ZmdibjhmVVh6Qg/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:05 INFO  - 2024-08-31 14:10:05,332 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,333 : Dispatching Event: autocomplete_completion
2024-08-31 15:10:05 INFO  - 2024-08-31 14:10:05,333 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:10:06 DEBUG - 2024-08-31 14:10:06,968 : 1279397932122247293::play should be called
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,007 : Successfully Reached /libraries with Status 200
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,007 : Valid Libraries Found: 1 | Name: Candlekeep
2024-08-31 15:10:07 
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,007 : Beginning to search libraries: Candlekeep | 87ea27a8-9cd1-4a2d-bb36-1a0f134321b4
2024-08-31 15:10:07 
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,137 : status code: 200
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : Successfully Reached /libraries/87ea27a8-9cd1-4a2d-bb36-1a0f134321b4/search with Status 200
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: The Empire Strikes Back | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Star Wars | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Star Wars Dark Forces Collectors Trilogy Audiobook | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Dark Empire Trilogy | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Return of the Jedi | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Harry Potter and the Goblet of Fire, Book 4 | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Harry Potter and the Prisoner of Azkaban, Book 3 | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Justice League of America - Wonder Woman - Mythos | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: DC Comics - Wayne of Gotham | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Justice League of America - The Flash - Stop Motion | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : [{'id': 'f506d5a6-27ce-4e38-9228-59b156f699f7', 'title': 'The Empire Strikes Back', 'author': 'Brian Daley, George Lucas'}, {'id': '38c695ff-8f54-4b02-b3bf-72ee78f83ebc', 'title': 'Star Wars', 'author': 'Brian Daley, George Lucas'}, {'id': 'e6a419b8-1067-449a-9b2b-2aa3bc7a7d1b', 'title': 'Star Wars Dark Forces Collectors Trilogy Audiobook', 'author': 'William C. Dietz, Dark Horse Comics, Dave Dorman'}, {'id': 'c5ab8df6-1a9d-4cd8-83b8-88a7f5570a93', 'title': 'Dark Empire Trilogy', 'author': 'Tom Veitch'}, {'id': '3acd4a54-26ec-4f90-83c5-76c960da46bd', 'title': 'Return of the Jedi', 'author': 'Brian Daley, George Lucas'}, {'id': '5c7d3911-1c57-45a0-9c06-1b7cccb11e60', 'title': 'Harry Potter and the Goblet of Fire, Book 4', 'author': 'J.K. Rowling'}, {'id': 'cc4f816e-e686-4657-a724-d963ef168431', 'title': 'Harry Potter and the Prisoner of Azkaban, Book 3', 'author': 'J.K. Rowling'}, {'id': 'd85231ee-3912-49b9-ad13-e9e3c61e4c49', 'title': 'Justice League of America - Wonder Woman - Mythos', 'author': 'GraphicAudio'}, {'id': '5b8898f7-634a-4f95-9f31-3f8be1cc5da5', 'title': 'DC Comics - Wayne of Gotham', 'author': 'GraphicAudio'}, {'id': 'f49fc9e4-695a-4bda-9176-cf54c2243f85', 'title': 'Justice League of America - The Flash - Stop Motion', 'author': 'GraphicAudio'}]
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,298 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,298 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzEwNDI0MzM4NDQ2NDpiRk1nWWhoREk1TzRGODk1cEp4TEJIZ3VQY1hPZmczSzNQNGxxUUtTZHNYMEZrNnlRVUh6YmFXd0VxdDJLbmk2MERockh2ZFpzMm5DQXdVNnB2M3JCeEh1Tmx0RDZFOTAwemNuTm9tMTVpS3E5OHB2MlBUQUlSWk5HSTdYemRyaA/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:07 INFO  - 2024-08-31 14:10:07,298 : [{'name': 'The Empire Strikes Back | Brian Daley, George Lucas', 'value': 'f506d5a6-27ce-4e38-9228-59b156f699f7'}, {'name': 'Star Wars | Brian Daley, George Lucas', 'value': '38c695ff-8f54-4b02-b3bf-72ee78f83ebc'}, {'name': 'Star Wars Dark Forces Collectors Trilogy Audiobook', 'value': 'e6a419b8-1067-449a-9b2b-2aa3bc7a7d1b'}, {'name': 'Dark Empire Trilogy | Tom Veitch', 'value': 'c5ab8df6-1a9d-4cd8-83b8-88a7f5570a93'}, {'name': 'Return of the Jedi | Brian Daley, George Lucas', 'value': '3acd4a54-26ec-4f90-83c5-76c960da46bd'}, {'name': 'Harry Potter and the Goblet of Fire, Book 4 | J.K. Rowling', 'value': '5c7d3911-1c57-45a0-9c06-1b7cccb11e60'}, {'name': 'Harry Potter and the Prisoner of Azkaban, Book 3 | J.K. Rowling', 'value': 'cc4f816e-e686-4657-a724-d963ef168431'}, {'name': 'Justice League of America - Wonder Woman - Mythos | GraphicAudio', 'value': 'd85231ee-3912-49b9-ad13-e9e3c61e4c49'}, {'name': 'DC Comics - Wayne of Gotham | GraphicAudio', 'value': '5b8898f7-634a-4f95-9f31-3f8be1cc5da5'}, {'name': 'Justice League of America - The Flash - Stop Motion | GraphicAudio', 'value': 'f49fc9e4-695a-4bda-9176-cf54c2243f85'}]
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,298 : Dispatching Event: autocomplete_completion
2024-08-31 15:10:07 INFO  - 2024-08-31 14:10:07,299 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': 'g'}
2024-08-31 15:10:09 DEBUG - 2024-08-31 14:10:09,739 : 1279397932122247293::play should be called
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,025 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,026 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzExNTgxNTYwNDM0NDpSamllYU81OTNwR09iVDVITFNrQ3gwaGVrQUFvOHczbzVvYmhwZ0l3VFBuVEFnd3d5MWFwTmhaRkFHdXphYmVsUlR1VE1uYmduQmN6dmxkc1MxOGVSamluSW1mTTRib1A0YjRUSUw2aFk0R251SVI5ZFAyd0hvN3VtR1hrbWc4Vg/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,280 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,280 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzExNTgxNTYwNDM0NDpSamllYU81OTNwR09iVDVITFNrQ3gwaGVrQUFvOHczbzVvYmhwZ0l3VFBuVEFnd3d5MWFwTmhaRkFHdXphYmVsUlR1VE1uYmduQmN6dmxkc1MxOGVSamluSW1mTTRib1A0YjRUSUw2aFk0R251SVI5ZFAyd0hvN3VtR1hrbWc4Vg/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,281 : Dispatching Event: command_completion
2024-08-31 15:10:10 INFO  - 2024-08-31 14:10:10,281 : Command Called: play with event.ctx.args = ['f506d5a6-27ce-4e38-9228-59b156f699f7'] | event.ctx.kwargs = {'book': 'f506d5a6-27ce-4e38-9228-59b156f699f7'}
2024-08-31 15:10:21 DEBUG - 2024-08-31 14:10:21,532 : Sending data to websocket: {"op": 1, "d": 8}
2024-08-31 15:10:21 DEBUG - 2024-08-31 14:10:21,533 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:10:21 DEBUG - 2024-08-31 14:10:21,634 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:10:30 DEBUG - 2024-08-31 14:10:30,855 : 1279397932122247293::play should be called
2024-08-31 15:10:31 DEBUG - 2024-08-31 14:10:31,095 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:31 DEBUG - 2024-08-31 14:10:31,095 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzIwMzkzMzczMjg2NjpBYmViVWM4Q1R1YWtrTzlVVWFvOVR2WlBVQjNXYkFJalZWcGVvYWxiUm5uenA5RThOV1UzVUhzN3lGd2twVENkeE1qa0xzRGhscERka2ZKejU4RFB2Nm50a05TdFkzUVE0bnFtaUpnV3VuVXBueFNlRXJoVTJhc2VpUUs4RGJPQQ/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:31 INFO  - 2024-08-31 14:10:31,095 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:10:31 DEBUG - 2024-08-31 14:10:31,096 : Dispatching Event: autocomplete_completion
2024-08-31 15:10:31 INFO  - 2024-08-31 14:10:31,096 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,345 : 1279397932122247293::play should be called
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,581 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,581 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzIxNDkwNTkwMTE1MDoxQnc4b093dFFsVlpodlAwMUpMempRZjY0N04zOE5QUFh4NHBER1Uwa3ZOWUxBZnc5c0swWm80RlVmSlVaVzN4NTNZQUtmWnRIWWRVdTdMVE9OR2dqZkg3NVZaTjFESzNnQWVRSzFHUkhMbkpteDgzbjdMUnEwVXRJSVo4TW1haQ/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,812 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,812 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzIxNDkwNTkwMTE1MDoxQnc4b093dFFsVlpodlAwMUpMempRZjY0N04zOE5QUFh4NHBER1Uwa3ZOWUxBZnc5c0swWm80RlVmSlVaVzN4NTNZQUtmWnRIWWRVdTdMVE9OR2dqZkg3NVZaTjFESzNnQWVRSzFHUkhMbkpteDgzbjdMUnEwVXRJSVo4TW1haQ/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,813 : Dispatching Event: command_completion
2024-08-31 15:10:33 INFO  - 2024-08-31 14:10:33,813 : Command Called: play with event.ctx.args = ['7247c259-b3ad-4fcc-a613-de771eaac71f'] | event.ctx.kwargs = {'book': '7247c259-b3ad-4fcc-a613-de771eaac71f'}
2024-08-31 15:11:02 DEBUG - 2024-08-31 14:11:02,792 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:11:02 DEBUG - 2024-08-31 14:11:02,793 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:11:02 DEBUG - 2024-08-31 14:11:02,891 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:11:44 DEBUG - 2024-08-31 14:11:44,084 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:11:44 DEBUG - 2024-08-31 14:11:44,084 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:11:44 DEBUG - 2024-08-31 14:11:44,199 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:12:25 DEBUG - 2024-08-31 14:12:25,345 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:12:25 DEBUG - 2024-08-31 14:12:25,345 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:12:25 DEBUG - 2024-08-31 14:12:25,445 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:13:06 DEBUG - 2024-08-31 14:13:06,602 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:13:06 DEBUG - 2024-08-31 14:13:06,603 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:13:06 DEBUG - 2024-08-31 14:13:06,700 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:13:38 DEBUG - 2024-08-31 14:13:38,864 : 1279397932122247293::play should be called
2024-08-31 15:13:39 DEBUG - 2024-08-31 14:13:39,101 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:13:39 DEBUG - 2024-08-31 14:13:39,102 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0Mzk5MjkzMjUyMDAxOTpES0VLc2pnRlByV08yQmV5eVNxNjU1dkY3THNreVNKbDNsSDlUVEhBVU9GR0tPVFRBdVJnRHVHbHJHQm4zQXIxdzltclB3cUFiWjBnQTQ4V0VRWFJEZHR3cG0zWjdJYkE4OWdTQjc5NW9DSFA3emhycXlvZG40ajZ6OWJGYnFvbg/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:13:39 INFO  - 2024-08-31 14:13:39,102 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:13:39 DEBUG - 2024-08-31 14:13:39,102 : Dispatching Event: autocomplete_completion
2024-08-31 15:13:39 INFO  - 2024-08-31 14:13:39,102 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,498 : 1279397932122247293::play should be called
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,733 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,733 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0NDAwNDA2NDQ2NDkyNzo3ZVY2aWRscUNxQk5JYWcyRm1CdGh1UjJEU0VQVXVJQnNIaDRPaXZqdUk2Rm91V0pNcjBqZWdpTldGU21MblJTeEFTZmdaS1didXptQ3BmR2VxZnBGTjBGdTNtVm9TWWJURmlJN09FdUhOV0o4UVlJZGNRNEE2RjJpc3JwNHlGNw/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,925 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,925 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0NDAwNDA2NDQ2NDkyNzo3ZVY2aWRscUNxQk5JYWcyRm1CdGh1UjJEU0VQVXVJQnNIaDRPaXZqdUk2Rm91V0pNcjBqZWdpTldGU21MblJTeEFTZmdaS1didXptQ3BmR2VxZnBGTjBGdTNtVm9TWWJURmlJN09FdUhOV0o4UVlJZGNRNEE2RjJpc3JwNHlGNw/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,926 : Dispatching Event: command_completion
2024-08-31 15:13:41 INFO  - 2024-08-31 14:13:41,926 : Command Called: play with event.ctx.args = ['7247c259-b3ad-4fcc-a613-de771eaac71f'] | event.ctx.kwargs = {'book': '7247c259-b3ad-4fcc-a613-de771eaac71f'}
2024-08-31 15:13:47 DEBUG - 2024-08-31 14:13:47,858 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:13:47 DEBUG - 2024-08-31 14:13:47,859 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:13:47 DEBUG - 2024-08-31 14:13:47,965 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:14:29 DEBUG - 2024-08-31 14:14:29,125 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:14:29 DEBUG - 2024-08-31 14:14:29,126 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:14:29 DEBUG - 2024-08-31 14:14:29,226 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:15:10 DEBUG - 2024-08-31 14:15:10,377 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:15:10 DEBUG - 2024-08-31 14:15:10,378 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:15:10 DEBUG - 2024-08-31 14:15:10,473 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:15:51 DEBUG - 2024-08-31 14:15:51,630 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:15:51 DEBUG - 2024-08-31 14:15:51,630 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:15:51 DEBUG - 2024-08-31 14:15:51,725 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway

Maybe I've set something up incorrectly?
In the docker run command I had to use

-e MULTI_USER=True \
-e OWNER_ONLY=False \
-e AUDIO_ENABLED=True \
-e DEBUG_MODE=True \

as the container logs would complain that they weren't strings.

@donkevlar
Copy link
Owner

donkevlar commented Aug 31, 2024

I'm trying to use this bot, but I keep getting the same message. The logs seem to indicate that it's correctly connected to abs, and the autocomplete and suggestions appear to work correctly.

2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,311 : Initializing wishlist table
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,325 : Initializing tasks table
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Bot is Starting Up! | Startup Time: 2024-08-31 10:01:17.346304-04:00
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Starting up bookshelf traveller v.V1.2.6
2024-08-31 15:01:17 WARNING - 2024-08-31 14:01:17,346 : Please wait for this process to finish prior to use, you have been warned!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Current config to follow!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : VERSION: V1.2.6
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : SERVER_URL: http://xxx.xxx.xxx.xxx:13378
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : TIMEZONE: America/Toronto
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : PLAYBACK_ROLE: 0
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : OWNER_ONLY: False
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : TASK_FREQUENCY: 5
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : AUDIO_ENABLED: True
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : MULTI_USER: True
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,346 : Testing Server Connection
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,356 : Connection Established!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,356 : Current Server Status = 200, Good to go!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,357 : Running client sanity checks...
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,357 : Providing Auth Token to Server
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,899 : Cleaning up, authentication
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,899 : Logging user in and verifying role.
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,899 : ABS user logged in as ADMIN with type: root
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,900 : Default Commands loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,904 : No setup function found in default_commands
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,904 : Found extension class PrimaryCommands in default_commands: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,905 : Added callback: PrimaryCommands.all_library_items
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.ping
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.search_book
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.search_media_progress
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.setup_default_commands
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.show_all_libraries
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.show_recent_sessions
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,906 : Added callback: PrimaryCommands.totalTime
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,907 : Loaded Extension: default_commands
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,907 : Context Menus module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,907 : No setup function found in context-menus
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Found extension class ContextMenus in context-menus: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Added callback: ContextMenus.repeat
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Loaded Extension: context-menus
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,908 : Subscribable Task module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : No setup function found in subscription_task
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Found extension class SubscriptionTask in subscription_task: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Added callback: SubscriptionTask.active_tasks_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,908 : Added callback: SubscriptionTask.newBookCheck
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: SubscriptionTask.remove_task_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: SubscriptionTask.task_setup
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Loaded Extension: subscription_task
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,909 : Wishlist module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : No setup function found in wishlist
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Found extension class WishList in wishlist: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: WishList.add_book_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: WishList.cancel_button_callback
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,909 : Added callback: WishList.on_search_menu_select
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.remove_book_command
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.request_button_callback
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.view_all_wishlists
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Added callback: WishList.view_wishlist
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,910 : Loaded Extension: wishlist
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,910 : Audio module loaded!
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,917 : No setup function found in audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Found extension class AudioPlayBack in audio: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_forward_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_next_chapter_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_pause_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_play_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_previous_chapter_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_rewind_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_stop_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_volume_down_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.callback_volume_up_button
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.change_chapter
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,918 : Added callback: AudioPlayBack.close_active_sessions
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.pause_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.play_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.refresh_play_card
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.resume_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.stop_audio
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Added callback: AudioPlayBack.volume_adjuster
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,919 : Loaded Extension: audio
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,919 : MULTI_USER module loaded!
2024-08-31 15:01:17 INFO  - 2024-08-31 14:01:17,922 : Initializing Sqlite DB
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : No setup function found in multi_user
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : Found extension class MultiUser in multi_user: Attempting to load
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : Added callback: MultiUser.remove_db_user
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,926 : Added callback: MultiUser.user_check
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Added callback: MultiUser.user_login
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Added callback: MultiUser.user_select_db
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Loaded Extension: multi_user
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Altering default database columns
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : Error occured while attempting to alter original databases
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,927 : duplicate column name: downloaded
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_startup
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : 1 callbacks have been loaded from __main__.
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: _disconnect
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: _on_websocket_ready
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_autocomplete_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_autocomplete_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_command_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_command_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_component_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_component_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,928 : Added callback: on_modal_completion
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : Added callback: on_modal_error
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : Added callback: on_resume
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : 12 callbacks have been loaded from Client.
2024-08-31 15:01:17 DEBUG - 2024-08-31 14:01:17,929 : Attempting to login
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,164 : Caching ingested rate limit data for: 78bb8553d9352a5a2f89f9def401287a
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,164 : GET /users/@me Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,456 : Caching ingested rate limit data for: d28a133af187e91d71d96a223467ce49
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,456 : GET /oauth2/applications/@me Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,508 : Caching ingested rate limit data for: 0a6d458104b103c7be293cc9b284d9ee
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,508 : GET /gateway Received 200 :: [99/100 calls remaining]
2024-08-31 15:01:18 INFO  - 2024-08-31 14:01:18,508 : Shard ID 0 | Starting Shard
2024-08-31 15:01:18 INFO  - 2024-08-31 14:01:18,508 : Shard ID 0 | Shard is attempting to connect to gateway...
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,672 : Shard ID 0 | Identification payload sent to gateway, requesting intents: 53706493
2024-08-31 15:01:18 INFO  - 2024-08-31 14:01:18,926 : Shard ID 0 | Gateway connection established
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,926 : Shard ID 0 | Session ID: c669219c192957e54ac856943f185764 Trace: ['["gateway-prd-us-east1-d-q0k3",{"micros":146669,"calls":["id_created",{"micros":671,"calls":[]},"session_lookup_time",{"micros":361,"calls":[]},"session_lookup_finished",{"micros":13,"calls":[]},"discord-sessions-prd-1-78",{"micros":142400,"calls":["start_session",{"micros":101061,"calls":["discord-api-rpc-6d68cf98f7-dvvdg",{"micros":89233,"calls":["get_user",{"micros":18047},"get_guilds",{"micros":20485},"send_scheduled_deletion_message",{"micros":7},"guild_join_requests",{"micros":2},"authorized_ip_coro",{"micros":6}]}]},"starting_guild_connect",{"micros":62,"calls":[]},"presence_started",{"micros":2659,"calls":[]},"guilds_started",{"micros":1,"calls":[]},"guilds_connect",{"micros":1,"calls":[]},"presence_connect",{"micros":38595,"calls":[]},"connect_finished",{"micros":38600,"calls":[]},"build_ready",{"micros":13,"calls":[]},"clean_ready",{"micros":0,"calls":[]},"optimize_ready",{"micros":1,"calls":[]},"split_ready",{"micros":0,"calls":[]}]}]}]']
2024-08-31 15:01:18 DEBUG - 2024-08-31 14:01:18,926 : Dispatching Event: websocket_ready
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,119 : Caching ingested rate limit data for: dd84d9818020c27975c0d5ab4a794d44
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,119 : GET /applications/{application_id}/commands Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,299 : Caching ingested rate limit data for: dd84d9818020c27975c0d5ab4a794d44
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,299 : GET /applications/{application_id}/commands Received 200 :: [999/1000 calls remaining]
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,300 : 0 is already up-to-date with 33 commands.
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,300 : Sync of 1 scopes took 0.3742651089996798 seconds
2024-08-31 15:01:19 DEBUG - 2024-08-31 14:01:19,300 : Dispatching Event: startup
2024-08-31 15:01:19 WARNING - 2024-08-31 14:01:19,300 : Initialized subscription task module, verifying if any tasks are enabled...
2024-08-31 15:01:19 INFO  - 2024-08-31 14:01:19,317 : Inserted: heckle with token and discord_id
2024-08-31 15:01:19 INFO  - 2024-08-31 14:01:19,317 : Registered initial user heckle successfully
2024-08-31 15:01:19 INFO  - 2024-08-31 14:01:19,317 : Bot has finished loading, it is now safe to use! :)
2024-08-31 15:01:24 DEBUG - 2024-08-31 14:01:24,932 : Sending heartbeat every 41.25 seconds
2024-08-31 15:01:24 DEBUG - 2024-08-31 14:01:24,932 : Sending data to websocket: {"op": 1, "d": 1}
2024-08-31 15:01:24 DEBUG - 2024-08-31 14:01:24,932 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:01:25 DEBUG - 2024-08-31 14:01:25,031 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,415 : 1279397932122247293::play should be called
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,719 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,719 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MTAwODcxNDU4NDE1NzpUcTRxanVsbnp2bHNrT0FwdFpBMEZ0dTFDbmJxbEh2UjlGRHlLaUxXZGtEbHZBQzFyUklJc25xbnVkRmR5cmVhR25kSXNWMGRST29qcmZmUDRvMGt2cEpjalBmRXROSGVTRUtHZWdGbXVPc2RaNDh0UkNlREF5Y3NtVHhpcExhdQ/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:01:47 INFO  - 2024-08-31 14:01:47,719 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:01:47 DEBUG - 2024-08-31 14:01:47,719 : Dispatching Event: autocomplete_completion
2024-08-31 15:01:47 INFO  - 2024-08-31 14:01:47,719 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:01:53 DEBUG - 2024-08-31 14:01:53,648 : 1279397932122247293::play should be called
2024-08-31 15:01:53 DEBUG - 2024-08-31 14:01:53,859 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:01:53 DEBUG - 2024-08-31 14:01:53,859 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MTAzNTE0MzE1MTY1NjoxUUFuczgzWEQzb2xGM01mUWM1R2lFNnN6Q3Nva3pVcDJDMTNyOGdqZVFubmo2UFNDWGVKOW8yUG1HV2podzNBMDFzR1lPUGp3cHp3MVBrazR3ZndEWmtmMER2MHpsZU1KVXZVaTNvQ0VNUnlVVHRLY2tMOFk2enBUTFFVdnJNag/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:01:54 DEBUG - 2024-08-31 14:01:54,074 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:01:54 DEBUG - 2024-08-31 14:01:54,074 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0MTAzNTE0MzE1MTY1NjoxUUFuczgzWEQzb2xGM01mUWM1R2lFNnN6Q3Nva3pVcDJDMTNyOGdqZVFubmo2UFNDWGVKOW8yUG1HV2podzNBMDFzR1lPUGp3cHp3MVBrazR3ZndEWmtmMER2MHpsZU1KVXZVaTNvQ0VNUnlVVHRLY2tMOFk2enBUTFFVdnJNag/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:01:54 DEBUG - 2024-08-31 14:01:54,075 : Dispatching Event: command_completion
2024-08-31 15:01:54 INFO  - 2024-08-31 14:01:54,075 : Command Called: play with event.ctx.args = ['7247c259-b3ad-4fcc-a613-de771eaac71f'] | event.ctx.kwargs = {'book': '7247c259-b3ad-4fcc-a613-de771eaac71f'}
2024-08-31 15:02:06 DEBUG - 2024-08-31 14:02:06,184 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:02:06 DEBUG - 2024-08-31 14:02:06,185 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:02:06 DEBUG - 2024-08-31 14:02:06,281 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:02:47 DEBUG - 2024-08-31 14:02:47,456 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:02:47 DEBUG - 2024-08-31 14:02:47,457 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:02:47 DEBUG - 2024-08-31 14:02:47,564 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:03:28 DEBUG - 2024-08-31 14:03:28,749 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:03:28 DEBUG - 2024-08-31 14:03:28,749 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:03:28 DEBUG - 2024-08-31 14:03:28,849 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:04:10 DEBUG - 2024-08-31 14:04:10,039 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:04:10 DEBUG - 2024-08-31 14:04:10,040 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:04:10 DEBUG - 2024-08-31 14:04:10,146 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:04:51 DEBUG - 2024-08-31 14:04:51,295 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:04:51 DEBUG - 2024-08-31 14:04:51,295 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:04:51 DEBUG - 2024-08-31 14:04:51,394 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:05:32 DEBUG - 2024-08-31 14:05:32,587 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:05:32 DEBUG - 2024-08-31 14:05:32,588 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:05:32 DEBUG - 2024-08-31 14:05:32,686 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:06:13 DEBUG - 2024-08-31 14:06:13,846 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:06:13 DEBUG - 2024-08-31 14:06:13,846 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:06:13 DEBUG - 2024-08-31 14:06:13,951 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:06:55 DEBUG - 2024-08-31 14:06:55,133 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:06:55 DEBUG - 2024-08-31 14:06:55,134 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:06:55 DEBUG - 2024-08-31 14:06:55,240 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:07:36 DEBUG - 2024-08-31 14:07:36,426 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:07:36 DEBUG - 2024-08-31 14:07:36,426 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:07:36 DEBUG - 2024-08-31 14:07:36,524 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:08:17 DEBUG - 2024-08-31 14:08:17,718 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:08:17 DEBUG - 2024-08-31 14:08:17,718 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:08:17 DEBUG - 2024-08-31 14:08:17,813 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:08:59 DEBUG - 2024-08-31 14:08:59,010 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:08:59 DEBUG - 2024-08-31 14:08:59,010 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:08:59 DEBUG - 2024-08-31 14:08:59,107 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:09:40 DEBUG - 2024-08-31 14:09:40,280 : Sending data to websocket: {"op": 1, "d": 4}
2024-08-31 15:09:40 DEBUG - 2024-08-31 14:09:40,280 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:09:40 DEBUG - 2024-08-31 14:09:40,375 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,087 : 1279397932122247293::play should be called
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,332 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,332 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzA5NjMwNzc2NTM3MDo4cjRiU2FoekpxYVZUazZKNUNZUzdTcjg0d3hxYWt0SHFmdFFrUmRZNjdTUzZIYVJFTDdHbnVUQ0V0czhHOHZNY0VJNGt3ZWlsb3dhR3FRSUVhR3A1bTRuNzU0Z3ppVTNVaXlmQ0tMRlU5UUMzMEdiTW8waDB4ZmdibjhmVVh6Qg/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:05 INFO  - 2024-08-31 14:10:05,332 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:10:05 DEBUG - 2024-08-31 14:10:05,333 : Dispatching Event: autocomplete_completion
2024-08-31 15:10:05 INFO  - 2024-08-31 14:10:05,333 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:10:06 DEBUG - 2024-08-31 14:10:06,968 : 1279397932122247293::play should be called
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,007 : Successfully Reached /libraries with Status 200
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,007 : Valid Libraries Found: 1 | Name: Candlekeep
2024-08-31 15:10:07 
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,007 : Beginning to search libraries: Candlekeep | 87ea27a8-9cd1-4a2d-bb36-1a0f134321b4
2024-08-31 15:10:07 
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,137 : status code: 200
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : Successfully Reached /libraries/87ea27a8-9cd1-4a2d-bb36-1a0f134321b4/search with Status 200
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: The Empire Strikes Back | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Star Wars | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Star Wars Dark Forces Collectors Trilogy Audiobook | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Dark Empire Trilogy | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Return of the Jedi | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Harry Potter and the Goblet of Fire, Book 4 | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Harry Potter and the Prisoner of Azkaban, Book 3 | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Justice League of America - Wonder Woman - Mythos | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: DC Comics - Wayne of Gotham | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : accepted: Justice League of America - The Flash - Stop Motion | media type: book
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,149 : [{'id': 'f506d5a6-27ce-4e38-9228-59b156f699f7', 'title': 'The Empire Strikes Back', 'author': 'Brian Daley, George Lucas'}, {'id': '38c695ff-8f54-4b02-b3bf-72ee78f83ebc', 'title': 'Star Wars', 'author': 'Brian Daley, George Lucas'}, {'id': 'e6a419b8-1067-449a-9b2b-2aa3bc7a7d1b', 'title': 'Star Wars Dark Forces Collectors Trilogy Audiobook', 'author': 'William C. Dietz, Dark Horse Comics, Dave Dorman'}, {'id': 'c5ab8df6-1a9d-4cd8-83b8-88a7f5570a93', 'title': 'Dark Empire Trilogy', 'author': 'Tom Veitch'}, {'id': '3acd4a54-26ec-4f90-83c5-76c960da46bd', 'title': 'Return of the Jedi', 'author': 'Brian Daley, George Lucas'}, {'id': '5c7d3911-1c57-45a0-9c06-1b7cccb11e60', 'title': 'Harry Potter and the Goblet of Fire, Book 4', 'author': 'J.K. Rowling'}, {'id': 'cc4f816e-e686-4657-a724-d963ef168431', 'title': 'Harry Potter and the Prisoner of Azkaban, Book 3', 'author': 'J.K. Rowling'}, {'id': 'd85231ee-3912-49b9-ad13-e9e3c61e4c49', 'title': 'Justice League of America - Wonder Woman - Mythos', 'author': 'GraphicAudio'}, {'id': '5b8898f7-634a-4f95-9f31-3f8be1cc5da5', 'title': 'DC Comics - Wayne of Gotham', 'author': 'GraphicAudio'}, {'id': 'f49fc9e4-695a-4bda-9176-cf54c2243f85', 'title': 'Justice League of America - The Flash - Stop Motion', 'author': 'GraphicAudio'}]
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,298 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,298 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzEwNDI0MzM4NDQ2NDpiRk1nWWhoREk1TzRGODk1cEp4TEJIZ3VQY1hPZmczSzNQNGxxUUtTZHNYMEZrNnlRVUh6YmFXd0VxdDJLbmk2MERockh2ZFpzMm5DQXdVNnB2M3JCeEh1Tmx0RDZFOTAwemNuTm9tMTVpS3E5OHB2MlBUQUlSWk5HSTdYemRyaA/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:07 INFO  - 2024-08-31 14:10:07,298 : [{'name': 'The Empire Strikes Back | Brian Daley, George Lucas', 'value': 'f506d5a6-27ce-4e38-9228-59b156f699f7'}, {'name': 'Star Wars | Brian Daley, George Lucas', 'value': '38c695ff-8f54-4b02-b3bf-72ee78f83ebc'}, {'name': 'Star Wars Dark Forces Collectors Trilogy Audiobook', 'value': 'e6a419b8-1067-449a-9b2b-2aa3bc7a7d1b'}, {'name': 'Dark Empire Trilogy | Tom Veitch', 'value': 'c5ab8df6-1a9d-4cd8-83b8-88a7f5570a93'}, {'name': 'Return of the Jedi | Brian Daley, George Lucas', 'value': '3acd4a54-26ec-4f90-83c5-76c960da46bd'}, {'name': 'Harry Potter and the Goblet of Fire, Book 4 | J.K. Rowling', 'value': '5c7d3911-1c57-45a0-9c06-1b7cccb11e60'}, {'name': 'Harry Potter and the Prisoner of Azkaban, Book 3 | J.K. Rowling', 'value': 'cc4f816e-e686-4657-a724-d963ef168431'}, {'name': 'Justice League of America - Wonder Woman - Mythos | GraphicAudio', 'value': 'd85231ee-3912-49b9-ad13-e9e3c61e4c49'}, {'name': 'DC Comics - Wayne of Gotham | GraphicAudio', 'value': '5b8898f7-634a-4f95-9f31-3f8be1cc5da5'}, {'name': 'Justice League of America - The Flash - Stop Motion | GraphicAudio', 'value': 'f49fc9e4-695a-4bda-9176-cf54c2243f85'}]
2024-08-31 15:10:07 DEBUG - 2024-08-31 14:10:07,298 : Dispatching Event: autocomplete_completion
2024-08-31 15:10:07 INFO  - 2024-08-31 14:10:07,299 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': 'g'}
2024-08-31 15:10:09 DEBUG - 2024-08-31 14:10:09,739 : 1279397932122247293::play should be called
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,025 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,026 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzExNTgxNTYwNDM0NDpSamllYU81OTNwR09iVDVITFNrQ3gwaGVrQUFvOHczbzVvYmhwZ0l3VFBuVEFnd3d5MWFwTmhaRkFHdXphYmVsUlR1VE1uYmduQmN6dmxkc1MxOGVSamluSW1mTTRib1A0YjRUSUw2aFk0R251SVI5ZFAyd0hvN3VtR1hrbWc4Vg/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,280 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,280 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzExNTgxNTYwNDM0NDpSamllYU81OTNwR09iVDVITFNrQ3gwaGVrQUFvOHczbzVvYmhwZ0l3VFBuVEFnd3d5MWFwTmhaRkFHdXphYmVsUlR1VE1uYmduQmN6dmxkc1MxOGVSamluSW1mTTRib1A0YjRUSUw2aFk0R251SVI5ZFAyd0hvN3VtR1hrbWc4Vg/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:10:10 DEBUG - 2024-08-31 14:10:10,281 : Dispatching Event: command_completion
2024-08-31 15:10:10 INFO  - 2024-08-31 14:10:10,281 : Command Called: play with event.ctx.args = ['f506d5a6-27ce-4e38-9228-59b156f699f7'] | event.ctx.kwargs = {'book': 'f506d5a6-27ce-4e38-9228-59b156f699f7'}
2024-08-31 15:10:21 DEBUG - 2024-08-31 14:10:21,532 : Sending data to websocket: {"op": 1, "d": 8}
2024-08-31 15:10:21 DEBUG - 2024-08-31 14:10:21,533 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:10:21 DEBUG - 2024-08-31 14:10:21,634 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:10:30 DEBUG - 2024-08-31 14:10:30,855 : 1279397932122247293::play should be called
2024-08-31 15:10:31 DEBUG - 2024-08-31 14:10:31,095 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:31 DEBUG - 2024-08-31 14:10:31,095 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzIwMzkzMzczMjg2NjpBYmViVWM4Q1R1YWtrTzlVVWFvOVR2WlBVQjNXYkFJalZWcGVvYWxiUm5uenA5RThOV1UzVUhzN3lGd2twVENkeE1qa0xzRGhscERka2ZKejU4RFB2Nm50a05TdFkzUVE0bnFtaUpnV3VuVXBueFNlRXJoVTJhc2VpUUs4RGJPQQ/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:31 INFO  - 2024-08-31 14:10:31,095 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:10:31 DEBUG - 2024-08-31 14:10:31,096 : Dispatching Event: autocomplete_completion
2024-08-31 15:10:31 INFO  - 2024-08-31 14:10:31,096 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,345 : 1279397932122247293::play should be called
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,581 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,581 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzIxNDkwNTkwMTE1MDoxQnc4b093dFFsVlpodlAwMUpMempRZjY0N04zOE5QUFh4NHBER1Uwa3ZOWUxBZnc5c0swWm80RlVmSlVaVzN4NTNZQUtmWnRIWWRVdTdMVE9OR2dqZkg3NVZaTjFESzNnQWVRSzFHUkhMbkpteDgzbjdMUnEwVXRJSVo4TW1haQ/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,812 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,812 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0MzIxNDkwNTkwMTE1MDoxQnc4b093dFFsVlpodlAwMUpMempRZjY0N04zOE5QUFh4NHBER1Uwa3ZOWUxBZnc5c0swWm80RlVmSlVaVzN4NTNZQUtmWnRIWWRVdTdMVE9OR2dqZkg3NVZaTjFESzNnQWVRSzFHUkhMbkpteDgzbjdMUnEwVXRJSVo4TW1haQ/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:10:33 DEBUG - 2024-08-31 14:10:33,813 : Dispatching Event: command_completion
2024-08-31 15:10:33 INFO  - 2024-08-31 14:10:33,813 : Command Called: play with event.ctx.args = ['7247c259-b3ad-4fcc-a613-de771eaac71f'] | event.ctx.kwargs = {'book': '7247c259-b3ad-4fcc-a613-de771eaac71f'}
2024-08-31 15:11:02 DEBUG - 2024-08-31 14:11:02,792 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:11:02 DEBUG - 2024-08-31 14:11:02,793 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:11:02 DEBUG - 2024-08-31 14:11:02,891 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:11:44 DEBUG - 2024-08-31 14:11:44,084 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:11:44 DEBUG - 2024-08-31 14:11:44,084 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:11:44 DEBUG - 2024-08-31 14:11:44,199 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:12:25 DEBUG - 2024-08-31 14:12:25,345 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:12:25 DEBUG - 2024-08-31 14:12:25,345 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:12:25 DEBUG - 2024-08-31 14:12:25,445 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:13:06 DEBUG - 2024-08-31 14:13:06,602 : Sending data to websocket: {"op": 1, "d": 11}
2024-08-31 15:13:06 DEBUG - 2024-08-31 14:13:06,603 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:13:06 DEBUG - 2024-08-31 14:13:06,700 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:13:38 DEBUG - 2024-08-31 14:13:38,864 : 1279397932122247293::play should be called
2024-08-31 15:13:39 DEBUG - 2024-08-31 14:13:39,101 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:13:39 DEBUG - 2024-08-31 14:13:39,102 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0Mzk5MjkzMjUyMDAxOTpES0VLc2pnRlByV08yQmV5eVNxNjU1dkY3THNreVNKbDNsSDlUVEhBVU9GR0tPVFRBdVJnRHVHbHJHQm4zQXIxdzltclB3cUFiWjBnQTQ4V0VRWFJEZHR3cG0zWjdJYkE4OWdTQjc5NW9DSFA3emhycXlvZG40ajZ6OWJGYnFvbg/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:13:39 INFO  - 2024-08-31 14:13:39,102 : [{'name': 'The Discovery | Katherine Applegate', 'value': '5fc73a3e-78b0-44ca-9e48-0a992901ff28'}, {'name': 'The Departure | Katherine Applegate', 'value': '8d672f35-c011-4ff0-b157-1220256b2e7a'}, {'name': 'Rivers of London | Ben Aaronovitch', 'value': '7247c259-b3ad-4fcc-a613-de771eaac71f'}]
2024-08-31 15:13:39 DEBUG - 2024-08-31 14:13:39,102 : Dispatching Event: autocomplete_completion
2024-08-31 15:13:39 INFO  - 2024-08-31 14:13:39,102 : Autocomplete Called: $play with event.ctx.focussed_option = SlashCommandOption() | event.ctx.kwargs = {'book': ''}
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,498 : 1279397932122247293::play should be called
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,733 : Caching ingested rate limit data for: 432f6223265d156004ac81366c5a948f
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,733 : POST /interactions/{interaction_id}/aW50ZXJhY3Rpb246MTI3OTQ0NDAwNDA2NDQ2NDkyNzo3ZVY2aWRscUNxQk5JYWcyRm1CdGh1UjJEU0VQVXVJQnNIaDRPaXZqdUk2Rm91V0pNcjBqZWdpTldGU21MblJTeEFTZmdaS1didXptQ3BmR2VxZnBGTjBGdTNtVm9TWWJURmlJN09FdUhOV0o4UVlJZGNRNEE2RjJpc3JwNHlGNw/callback Received 204 :: [4/5 calls remaining]
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,925 : Caching ingested rate limit data for: 3d2712a9e4fe17cc9d3fed4a8e672e5f
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,925 : GET /webhooks/{application_id}/aW50ZXJhY3Rpb246MTI3OTQ0NDAwNDA2NDQ2NDkyNzo3ZVY2aWRscUNxQk5JYWcyRm1CdGh1UjJEU0VQVXVJQnNIaDRPaXZqdUk2Rm91V0pNcjBqZWdpTldGU21MblJTeEFTZmdaS1didXptQ3BmR2VxZnBGTjBGdTNtVm9TWWJURmlJN09FdUhOV0o4UVlJZGNRNEE2RjJpc3JwNHlGNw/messages/{message_id} Received 200 :: [4/5 calls remaining]
2024-08-31 15:13:41 DEBUG - 2024-08-31 14:13:41,926 : Dispatching Event: command_completion
2024-08-31 15:13:41 INFO  - 2024-08-31 14:13:41,926 : Command Called: play with event.ctx.args = ['7247c259-b3ad-4fcc-a613-de771eaac71f'] | event.ctx.kwargs = {'book': '7247c259-b3ad-4fcc-a613-de771eaac71f'}
2024-08-31 15:13:47 DEBUG - 2024-08-31 14:13:47,858 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:13:47 DEBUG - 2024-08-31 14:13:47,859 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:13:47 DEBUG - 2024-08-31 14:13:47,965 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:14:29 DEBUG - 2024-08-31 14:14:29,125 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:14:29 DEBUG - 2024-08-31 14:14:29,126 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:14:29 DEBUG - 2024-08-31 14:14:29,226 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:15:10 DEBUG - 2024-08-31 14:15:10,377 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:15:10 DEBUG - 2024-08-31 14:15:10,378 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:15:10 DEBUG - 2024-08-31 14:15:10,473 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway
2024-08-31 15:15:51 DEBUG - 2024-08-31 14:15:51,630 : Sending data to websocket: {"op": 1, "d": 14}
2024-08-31 15:15:51 DEBUG - 2024-08-31 14:15:51,630 : Shard ID 0 | ❤ Gateway is sending a Heartbeat
2024-08-31 15:15:51 DEBUG - 2024-08-31 14:15:51,725 : Shard ID 0 | ❤ Received heartbeat acknowledgement from gateway

Maybe I've set something up incorrectly? In the docker run command I had to use

-e MULTI_USER=True \
-e OWNER_ONLY=False \
-e AUDIO_ENABLED=True \
-e DEBUG_MODE=True \

as the container logs would complain that they weren't strings.

Everything looks like it's setup correctly, those parameters are also good. The type errors are expected for empty environment variables.

So 1st troubleshooting step would be to verify that you are connected to a voice channel that the bot is allowed to be in. You can check that in your channel settings. The bot should have a role in there with the same name. Here's a few prerequisites that the bot looks for (some of these I'm working on loosening in the future but haven't had a chance to fix it just yet). 1)The bot assumes you are connected to a voice channel when using the /play command. 2) it assumes that you have given it permission to be in same the channel as you. 3) it assumes that you have a connection to ABS.

2nd is to make sure you have full intents enabled in the bot dev portal enabled.

I'm going to look into your logs a bit more before concluding anything.

Try these and if it works then awesome.

@donkevlar donkevlar self-assigned this Aug 31, 2024
@donkevlar donkevlar added the question Further information is requested label Aug 31, 2024
@donkevlar donkevlar pinned this issue Aug 31, 2024
@HeckleCode
Copy link
Author

I'm not entirely certain how I fixed it, but I did. I created a new server and added the bot to it - I also gave it a bunch of permissions in the dev portal, most of which I'm fairly certain were unnessessary.
image

It may have also been that the original server was misconfigured in some way.
I appreciate the swift response.

@donkevlar
Copy link
Owner

Just skimmed through everything and I don't think you're running into any errors on the server side (whats running the bot) looks to be a permission issue or a client issue (need to reload discord).

The context behind this is the first check is this (there is other error checking but thats long and tedious and wont get into that)

if not self.bot.is_ready or not ctx.author.voice:
     await ctx.send(content="Bot is not ready or author not in voice channel, please try again later.", ephemeral=True)
     return

@donkevlar
Copy link
Owner

Pinning this for future peeps in case they run into something similar.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
question Further information is requested
Projects
None yet
Development

No branches or pull requests

2 participants