### Severity
Trivial
### Versions
20.15
### Components/Modules
extensions.c…onf
### Operating Environment
Python: 3.10
Ubuntu: 22.04
Asterisk: 20.15
VOIP provider: Messagenet
### Frequency of Occurrence
None
### Issue Description
Currently, I am trying to build voice call bot with python3.10 and asterisk.
There is STT and TTS step.
I use AGI code ```EXEC Playback {filename}``` to play audio file and ```RECORD FILE {recording_base} wav # {timeout} 0 BEEP s={silence}``` in the python to record user input.
I confirmed that I hear the audio file that is generated from TTS and recorded user speech into targeted path and file by using above command, SEVERAL TIMES.
But in the python log, the result of the above commands is ```510 Invalid or unknown command```.
That is the problem.
There are multiple turns and steps that needs to be run in order, but due to above results, bots moves forwards to the next step without waiting the result.
But in the asterisk log (sudo asterisk -rvvv), it shows in order.
For example, I am on the turn 1 but python log shows until turn 5, but asterisk log is on the turn 1.
I attached python bot codes.
[agi_handler.py](https://github.com/user-attachments/files/22733805/agi_handler.py)
[dhub_voice_bot.py](https://github.com/user-attachments/files/22733806/dhub_voice_bot.py)
I also attached the python log(agi.log) and it reflects the turn 1 step (15s after connect the call)
### Relevant log output
```shell
2025-10-07 04:30:52,236 - __main__ - INFO - === COMPLETE VOICE BOT STARTING ===
2025-10-07 04:30:52,236 - __main__ - INFO - Python executable: /usr/bin/python3
2025-10-07 04:30:52,236 - __main__ - INFO - Arguments: ['/opt/dhub-voice-bot/src/dhub_voice_bot.py', '04221627525', '+3904381736733', '2']
2025-10-07 04:30:52,236 - __main__ - INFO - Working directory: /
2025-10-07 04:30:52,236 - __main__ - INFO - Call parameters: DID=04221627525, Caller=+3904381736733, Company=2
2025-10-07 04:30:52,236 - agi_handler - INFO - AGI Environment: {'agi_request': '/opt/dhub-voice-bot/src/dhub_voice_bot.py', 'agi_channel': 'PJSIP/messagenet-company1-000000a6', 'agi_language': 'en', 'agi_type': 'PJSIP', 'agi_uniqueid': '1759804251.330', 'agi_version': '20.15.2', 'agi_callerid': '+3904381736733', 'agi_calleridname': '+3904381736733', 'agi_callingpres': '0', 'agi_callingani2': '0', 'agi_callington': '0', 'agi_callingtns': '0', 'agi_dnid': '5406601152', 'agi_rdnis': 'unknown', 'agi_context': 'voice-bot-handler', 'agi_extension': 's', 'agi_priority': '8', 'agi_enhanced': '0.0', 'agi_accountcode': '', 'agi_threadid': '140019294438976', 'agi_arg_1': '04221627525', 'agi_arg_2': '+3904381736733', 'agi_arg_3': '2'}
2025-10-07 04:30:52,236 - __main__ - INFO - Voice bot initialization starting...
2025-10-07 04:30:52,236 - __main__ - INFO - Initializing STT...
2025-10-07 04:31:19,109 - __main__ - INFO - === COMPLETE VOICE BOT STARTING ===
2025-10-07 04:31:19,109 - __main__ - INFO - Python executable: /opt/dhub-voice-bot/venv/bin/python3
2025-10-07 04:31:19,109 - __main__ - INFO - Arguments: ['/opt/dhub-voice-bot/src/dhub_voice_bot.py', '--help']
2025-10-07 04:31:19,109 - __main__ - INFO - Working directory: /opt/dhub-voice-bot
2025-10-07 04:31:19,110 - __main__ - INFO - Call parameters: DID=--help, Caller=, Company=None
2025-10-07 04:31:23,177 - __main__ - INFO - === COMPLETE VOICE BOT STARTING ===
2025-10-07 04:31:23,177 - __main__ - INFO - Python executable: /usr/bin/python3
2025-10-07 04:31:23,177 - __main__ - INFO - Arguments: ['/opt/dhub-voice-bot/src/dhub_voice_bot.py', '--help']
2025-10-07 04:31:23,177 - __main__ - INFO - Working directory: /opt/dhub-voice-bot
2025-10-07 04:31:23,177 - __main__ - INFO - Call parameters: DID=--help, Caller=, Company=None
2025-10-07 04:31:30,761 - __main__ - INFO - === COMPLETE VOICE BOT STARTING ===
2025-10-07 04:31:30,761 - __main__ - INFO - Python executable: /usr/bin/python3
2025-10-07 04:31:30,761 - __main__ - INFO - Arguments: ['/opt/dhub-voice-bot/src/dhub_voice_bot.py', '04221627525', '+3904381736733', '2']
2025-10-07 04:31:30,761 - __main__ - INFO - Working directory: /
2025-10-07 04:31:30,761 - __main__ - INFO - Call parameters: DID=04221627525, Caller=+3904381736733, Company=2
2025-10-07 04:31:30,761 - agi_handler - INFO - AGI Environment: {'agi_request': '/opt/dhub-voice-bot/src/dhub_voice_bot.py', 'agi_channel': 'PJSIP/messagenet-company1-000000a7', 'agi_language': 'en', 'agi_type': 'PJSIP', 'agi_uniqueid': '1759804290.332', 'agi_version': '20.15.2', 'agi_callerid': '+3904381736733', 'agi_calleridname': '+3904381736733', 'agi_callingpres': '0', 'agi_callingani2': '0', 'agi_callington': '0', 'agi_callingtns': '0', 'agi_dnid': '5406601152', 'agi_rdnis': 'unknown', 'agi_context': 'voice-bot-handler', 'agi_extension': 's', 'agi_priority': '8', 'agi_enhanced': '0.0', 'agi_accountcode': '', 'agi_threadid': '140019296998976', 'agi_arg_1': '04221627525', 'agi_arg_2': '+3904381736733', 'agi_arg_3': '2'}
2025-10-07 04:31:30,761 - __main__ - INFO - Voice bot initialization starting...
2025-10-07 04:31:30,761 - __main__ - INFO - Initializing STT...
2025-10-07 04:31:32,087 - __main__ - INFO - STT: Using CUDA for Whisper base model
2025-10-07 04:31:32,087 - __main__ - INFO - Initializing TTS...
2025-10-07 04:31:35,056 - __main__ - INFO - TTS: Using Coqui TTS with CPU
2025-10-07 04:31:35,056 - __main__ - INFO - Voice bot initialization completed
2025-10-07 04:31:35,056 - __main__ - INFO - Handling call from +3904381736733 to 04221627525
2025-10-07 04:31:35,056 - __main__ - INFO - Playing: Welcome to the D-Hub platform. How can I help you ...
2025-10-07 04:31:35,268 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:35,268 - __main__ - INFO - TTS file generated: /tmp/tmpeip_f4mq.wav (212076 bytes)
2025-10-07 04:31:35,269 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804295.gsm
2025-10-07 04:31:35,269 - __main__ - INFO - Converting to GSM: /tmp/tmpeip_f4mq.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804295.gsm
2025-10-07 04:31:35,276 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804295.gsm
2025-10-07 04:31:35,277 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804295.gsm (7953 bytes)
2025-10-07 04:31:35,277 - __main__ - INFO - File permissions: 0o100644, Size: 7953
2025-10-07 04:31:35,277 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804295
2025-10-07 04:31:35,277 - __main__ - INFO - Playback result: 510 Invalid or unknown command
2025-10-07 04:31:35,277 - __main__ - WARNING - Playback response: 510 Invalid or unknown command
2025-10-07 04:31:35,658 - __main__ - INFO - Processing call for tenant: cliniche odontoiatriche maxillo facciali srls (ID: 2)
2025-10-07 04:31:36,020 - __main__ - INFO - Initializing AI Agent for tenant 2...
2025-10-07 04:31:36,778 - __main__ - INFO - AI Agent initialized successfully
2025-10-07 04:31:36,778 - __main__ - INFO - Starting conversation loop...
2025-10-07 04:31:36,778 - __main__ - INFO - === Conversation turn 1 ===
2025-10-07 04:31:36,778 - __main__ - INFO - Step 1: Recording user input...
2025-10-07 04:31:36,779 - __main__ - INFO - Recording directory permissions set: /var/lib/asterisk/sounds/recordings
2025-10-07 04:31:36,779 - __main__ - INFO - Recording to: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804296
2025-10-07 04:31:36,779 - __main__ - INFO - Record result: 510 Invalid or unknown command
2025-10-07 04:31:37,780 - __main__ - INFO - Waiting for file... attempt 1
2025-10-07 04:31:38,281 - __main__ - INFO - Waiting for file... attempt 2
2025-10-07 04:31:38,782 - __main__ - INFO - Waiting for file... attempt 3
2025-10-07 04:31:39,283 - __main__ - INFO - Waiting for file... attempt 4
2025-10-07 04:31:39,784 - __main__ - INFO - Waiting for file... attempt 5
2025-10-07 04:31:40,285 - __main__ - ERROR - File never created: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804296.wav
2025-10-07 04:31:40,286 - __main__ - INFO - Files in /var/lib/asterisk/sounds/recordings: ['rec_a9b85930-84bc-47c5-bc41-7ae2ddab1b56_1759420467.wav', 'rec_6ffab7fa-2b6a-456a-96c5-e38efcfecc18_1759802592.wav', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801155.WAV', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801159.WAV']
2025-10-07 04:31:40,286 - __main__ - INFO - Recording completed: None
2025-10-07 04:31:40,286 - __main__ - WARNING - No audio file returned from recording
2025-10-07 04:31:40,286 - __main__ - INFO - Playing: I didn't hear anything. Can you please repeat?...
2025-10-07 04:31:40,416 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:40,416 - __main__ - INFO - TTS file generated: /tmp/tmp55f02hz6.wav (172140 bytes)
2025-10-07 04:31:40,417 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300.gsm
2025-10-07 04:31:40,417 - __main__ - INFO - Converting to GSM: /tmp/tmp55f02hz6.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300.gsm
2025-10-07 04:31:40,423 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300.gsm
2025-10-07 04:31:40,423 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300.gsm (6468 bytes)
2025-10-07 04:31:40,423 - __main__ - INFO - File permissions: 0o100644, Size: 6468
2025-10-07 04:31:40,423 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300
2025-10-07 04:31:40,423 - __main__ - INFO - Playback result: 510 Invalid or unknown command
2025-10-07 04:31:40,423 - __main__ - WARNING - Playback response: 510 Invalid or unknown command
2025-10-07 04:31:40,423 - __main__ - INFO - === Conversation turn 2 ===
2025-10-07 04:31:40,423 - __main__ - INFO - Step 1: Recording user input...
2025-10-07 04:31:40,424 - __main__ - INFO - Recording directory permissions set: /var/lib/asterisk/sounds/recordings
2025-10-07 04:31:40,424 - __main__ - INFO - Recording to: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300
2025-10-07 04:31:40,424 - __main__ - INFO - Record result: 510 Invalid or unknown command
2025-10-07 04:31:41,425 - __main__ - INFO - Waiting for file... attempt 1
2025-10-07 04:31:41,927 - __main__ - INFO - Waiting for file... attempt 2
2025-10-07 04:31:42,428 - __main__ - INFO - Waiting for file... attempt 3
2025-10-07 04:31:42,929 - __main__ - INFO - Waiting for file... attempt 4
2025-10-07 04:31:43,430 - __main__ - INFO - Waiting for file... attempt 5
2025-10-07 04:31:43,931 - __main__ - ERROR - File never created: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804300.wav
2025-10-07 04:31:43,931 - __main__ - INFO - Files in /var/lib/asterisk/sounds/recordings: ['rec_a9b85930-84bc-47c5-bc41-7ae2ddab1b56_1759420467.wav', 'rec_6ffab7fa-2b6a-456a-96c5-e38efcfecc18_1759802592.wav', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801155.WAV', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801159.WAV', 'rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804296.wav']
2025-10-07 04:31:43,932 - __main__ - INFO - Recording completed: None
2025-10-07 04:31:43,932 - __main__ - WARNING - No audio file returned from recording
2025-10-07 04:31:43,932 - __main__ - INFO - Playing: I didn't hear anything. Can you please repeat?...
2025-10-07 04:31:44,030 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:44,030 - __main__ - INFO - TTS file generated: /tmp/tmpmavsee3u.wav (172140 bytes)
2025-10-07 04:31:44,031 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304.gsm
2025-10-07 04:31:44,031 - __main__ - INFO - Converting to GSM: /tmp/tmpmavsee3u.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304.gsm
2025-10-07 04:31:44,039 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304.gsm
2025-10-07 04:31:44,039 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304.gsm (6468 bytes)
2025-10-07 04:31:44,039 - __main__ - INFO - File permissions: 0o100644, Size: 6468
2025-10-07 04:31:44,039 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304
2025-10-07 04:31:44,039 - __main__ - INFO - Playback result: 510 Invalid or unknown command
2025-10-07 04:31:44,039 - __main__ - WARNING - Playback response: 510 Invalid or unknown command
2025-10-07 04:31:44,039 - __main__ - INFO - === Conversation turn 3 ===
2025-10-07 04:31:44,039 - __main__ - INFO - Step 1: Recording user input...
2025-10-07 04:31:44,041 - __main__ - INFO - Recording directory permissions set: /var/lib/asterisk/sounds/recordings
2025-10-07 04:31:44,041 - __main__ - INFO - Recording to: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304
2025-10-07 04:31:44,041 - __main__ - INFO - Record result: 510 Invalid or unknown command
2025-10-07 04:31:45,042 - __main__ - INFO - Waiting for file... attempt 1
2025-10-07 04:31:45,543 - __main__ - INFO - Waiting for file... attempt 2
2025-10-07 04:31:46,044 - __main__ - INFO - Waiting for file... attempt 3
2025-10-07 04:31:46,545 - __main__ - INFO - Waiting for file... attempt 4
2025-10-07 04:31:47,046 - __main__ - INFO - Waiting for file... attempt 5
2025-10-07 04:31:47,547 - __main__ - ERROR - File never created: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804304.wav
2025-10-07 04:31:47,547 - __main__ - INFO - Files in /var/lib/asterisk/sounds/recordings: ['rec_a9b85930-84bc-47c5-bc41-7ae2ddab1b56_1759420467.wav', 'rec_6ffab7fa-2b6a-456a-96c5-e38efcfecc18_1759802592.wav', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801155.WAV', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801159.WAV', 'rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804296.wav']
2025-10-07 04:31:47,548 - __main__ - INFO - Recording completed: None
2025-10-07 04:31:47,548 - __main__ - WARNING - No audio file returned from recording
2025-10-07 04:31:47,548 - __main__ - INFO - Playing: I didn't hear anything. Can you please repeat?...
2025-10-07 04:31:47,647 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:47,647 - __main__ - INFO - TTS file generated: /tmp/tmpzups42zw.wav (172140 bytes)
2025-10-07 04:31:47,648 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307.gsm
2025-10-07 04:31:47,648 - __main__ - INFO - Converting to GSM: /tmp/tmpzups42zw.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307.gsm
2025-10-07 04:31:47,655 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307.gsm
2025-10-07 04:31:47,655 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307.gsm (6468 bytes)
2025-10-07 04:31:47,656 - __main__ - INFO - File permissions: 0o100644, Size: 6468
2025-10-07 04:31:47,656 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307
2025-10-07 04:31:47,656 - __main__ - INFO - Playback result: 510 Invalid or unknown command
2025-10-07 04:31:47,656 - __main__ - WARNING - Playback response: 510 Invalid or unknown command
2025-10-07 04:31:47,656 - __main__ - INFO - === Conversation turn 4 ===
2025-10-07 04:31:47,656 - __main__ - INFO - Step 1: Recording user input...
2025-10-07 04:31:47,657 - __main__ - INFO - Recording directory permissions set: /var/lib/asterisk/sounds/recordings
2025-10-07 04:31:47,657 - __main__ - INFO - Recording to: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307
2025-10-07 04:31:47,657 - __main__ - INFO - Record result: 200 result=1
2025-10-07 04:31:48,658 - __main__ - INFO - Waiting for file... attempt 1
2025-10-07 04:31:49,159 - __main__ - INFO - Waiting for file... attempt 2
2025-10-07 04:31:49,661 - __main__ - INFO - Waiting for file... attempt 3
2025-10-07 04:31:50,162 - __main__ - INFO - Waiting for file... attempt 4
2025-10-07 04:31:50,663 - __main__ - INFO - Waiting for file... attempt 5
2025-10-07 04:31:51,164 - __main__ - ERROR - File never created: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804307.wav
2025-10-07 04:31:51,165 - __main__ - INFO - Files in /var/lib/asterisk/sounds/recordings: ['rec_a9b85930-84bc-47c5-bc41-7ae2ddab1b56_1759420467.wav', 'rec_6ffab7fa-2b6a-456a-96c5-e38efcfecc18_1759802592.wav', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801155.WAV', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801159.WAV', 'rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804296.wav']
2025-10-07 04:31:51,165 - __main__ - INFO - Recording completed: None
2025-10-07 04:31:51,165 - __main__ - WARNING - No audio file returned from recording
2025-10-07 04:31:51,165 - __main__ - INFO - Playing: I didn't hear anything. Can you please repeat?...
2025-10-07 04:31:51,262 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:51,262 - __main__ - INFO - TTS file generated: /tmp/tmp6dk90qm9.wav (172140 bytes)
2025-10-07 04:31:51,263 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311.gsm
2025-10-07 04:31:51,263 - __main__ - INFO - Converting to GSM: /tmp/tmp6dk90qm9.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311.gsm
2025-10-07 04:31:51,269 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311.gsm
2025-10-07 04:31:51,270 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311.gsm (6468 bytes)
2025-10-07 04:31:51,270 - __main__ - INFO - File permissions: 0o100644, Size: 6468
2025-10-07 04:31:51,270 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311
2025-10-07 04:31:51,270 - __main__ - INFO - Playback result: 200 result=1
2025-10-07 04:31:51,270 - __main__ - INFO - TTS played successfully
2025-10-07 04:31:51,270 - __main__ - INFO - === Conversation turn 5 ===
2025-10-07 04:31:51,270 - __main__ - INFO - Step 1: Recording user input...
2025-10-07 04:31:51,271 - __main__ - INFO - Recording directory permissions set: /var/lib/asterisk/sounds/recordings
2025-10-07 04:31:51,271 - __main__ - INFO - Recording to: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311
2025-10-07 04:31:51,271 - __main__ - INFO - Record result: 200 result=1
2025-10-07 04:31:52,272 - __main__ - INFO - Waiting for file... attempt 1
2025-10-07 04:31:52,773 - __main__ - INFO - Waiting for file... attempt 2
2025-10-07 04:31:53,274 - __main__ - INFO - Waiting for file... attempt 3
2025-10-07 04:31:53,775 - __main__ - INFO - Waiting for file... attempt 4
2025-10-07 04:31:54,276 - __main__ - INFO - Waiting for file... attempt 5
2025-10-07 04:31:54,777 - __main__ - ERROR - File never created: /var/lib/asterisk/sounds/recordings/rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804311.wav
2025-10-07 04:31:54,778 - __main__ - INFO - Files in /var/lib/asterisk/sounds/recordings: ['rec_a9b85930-84bc-47c5-bc41-7ae2ddab1b56_1759420467.wav', 'rec_6ffab7fa-2b6a-456a-96c5-e38efcfecc18_1759802592.wav', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801155.WAV', 'rec_6d64be75-c890-4891-bcf1-772cda2a0ba8_1759801159.WAV', 'rec_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804296.wav']
2025-10-07 04:31:54,778 - __main__ - INFO - Recording completed: None
2025-10-07 04:31:54,778 - __main__ - WARNING - No audio file returned from recording
2025-10-07 04:31:54,778 - __main__ - INFO - Playing: I didn't hear anything. Can you please repeat?...
2025-10-07 04:31:54,874 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:54,875 - __main__ - INFO - TTS file generated: /tmp/tmp6o3jpz5x.wav (172140 bytes)
2025-10-07 04:31:54,876 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804314.gsm
2025-10-07 04:31:54,876 - __main__ - INFO - Converting to GSM: /tmp/tmp6o3jpz5x.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804314.gsm
2025-10-07 04:31:54,882 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804314.gsm
2025-10-07 04:31:54,882 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804314.gsm (6468 bytes)
2025-10-07 04:31:54,882 - __main__ - INFO - File permissions: 0o100644, Size: 6468
2025-10-07 04:31:54,882 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804314
2025-10-07 04:31:54,883 - __main__ - INFO - Playback result: 200 result=1
2025-10-07 04:31:54,883 - __main__ - INFO - TTS played successfully
2025-10-07 04:31:54,883 - __main__ - INFO - Conversation loop ended
2025-10-07 04:31:54,883 - __main__ - INFO - Playing: Thank you for calling D-Hub. For more information,...
2025-10-07 04:31:55,126 - __main__ - INFO - Generated TTS with Coqui
2025-10-07 04:31:55,126 - __main__ - INFO - TTS file generated: /tmp/tmpbnmksu4y.wav (365196 bytes)
2025-10-07 04:31:55,127 - __main__ - INFO - Target audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804315.gsm
2025-10-07 04:31:55,127 - __main__ - INFO - Converting to GSM: /tmp/tmpbnmksu4y.wav -> /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804315.gsm
2025-10-07 04:31:55,135 - __main__ - INFO - Set ownership to asterisk:asterisk for /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804315.gsm
2025-10-07 04:31:55,136 - __main__ - INFO - Sox GSM conversion successful: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804315.gsm (13695 bytes)
2025-10-07 04:31:55,136 - __main__ - INFO - File permissions: 0o100644, Size: 13695
2025-10-07 04:31:55,136 - __main__ - INFO - Playing audio file: /var/lib/asterisk/sounds/custom/tts_b97a8ab1-f8c9-404e-b34d-39ae4c8f559f_1759804315
2025-10-07 04:31:55,136 - __main__ - INFO - Playback result: 200 result=1
2025-10-07 04:31:55,136 - __main__ - INFO - TTS played successfully
2025-10-07 04:31:55,136 - __main__ - INFO - Ending call...
2025-10-07 04:31:55,517 - __main__ - INFO - Call ended: b97a8ab1-f8c9-404e-b34d-39ae4c8f559f
2025-10-07 04:31:55,518 - __main__ - INFO - Call completed successfully
2025-10-07 04:31:55,518 - __main__ - INFO - === COMPLETE VOICE BOT ENDING ===
```
### Asterisk Issue Guidelines
- [x] Yes, I have read the Asterisk Issue Guidelines