07/04/07 11:52:46 4320 Toolkit config.py:609 DEBUG System hostname of rosie.mvc.mcc.ac.uk is valid 07/04/07 11:52:46 4320 Toolkit toolkit.py:116 DEBUG Initializing AG Toolkit version 3.1 beta1 07/04/07 11:52:46 4320 Toolkit toolkit.py:117 INFO Command and arguments: ['C:\\Documents and Settings\\zzalsar4\\Application Data\\AccessGrid3\\local_services\\AudioService\\AudioService.py', '--port', '58691', '--serviceManagerUri', 'http://rosie.mvc.mcc.ac.uk:11000/ServiceManager', '--token', '8258014916440e7c6f730f8546df3872'] 07/04/07 11:52:46 4320 VenueClient preferences.py:188 DEBUG Preferences.LoadPreferences: open file 07/04/07 11:52:46 4320 Toolkit toolkit.py:166 INFO Logfile Name: AudioService.log 07/04/07 11:52:46 4320 Toolkit toolkit.py:691 INFO Service init: have profile None 07/04/07 11:52:46 4320 Toolkit toolkit.py:716 INFO Service Initialization Complete. 07/04/07 11:52:46 4320 Toolkit agservice.py:393 INFO Starting Service URI: http://rosie.mvc.mcc.ac.uk:58691/Services/AudioService.825801490a1c0e7c6fc03aec82bdf4b5 07/04/07 11:52:46 4320 Toolkit agservice.py:403 DEBUG Registering with service manager; url=http://rosie.mvc.mcc.ac.uk:11000/ServiceManager 07/04/07 11:52:46 4228 Toolkit agservice.py:166 INFO SetConfiguration: Unrecognized parameter ignored: Force AC97 07/04/07 11:52:46 4228 Toolkit audioservice.py:349 INFO SetIdentity: Andrew Rowley Andrew.Rowley@manchester.ac.uk 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, L16, 16000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, L16, 8000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, L8, 16000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, L8, 8000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, PCMU, 16000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, PCMU, 8000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, GSM, 16000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service consumer, audio, 825801490a1c0e7c6fb20168de822308, GSM, 8000, 1, None, None 07/04/07 11:52:46 5268 Toolkit agservice.py:285 DEBUG GetDescription:Capability in Service producer, audio, 825801490a1c0e7c6fb20168de822308, L16, 16000, 1, None, None 07/04/07 11:52:48 5224 Toolkit agservice.py:239 INFO AGService.SetEnabled : enabled = 1 07/04/07 11:53:25 5120 Toolkit agservice.py:183 INFO SetStream: [consumer, audio, 825801490a1c0e7c6fb20168de822308, L16, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, L16, 8000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, L8, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, L8, 8000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, PCMU, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, PCMU, 8000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, GSM, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, GSM, 8000, 1, None, None, producer, audio, 825801490a1c0e7c6fb20168de822308, L16, 16000, 1, None, None] 224.2.240.144 63466 07/04/07 11:53:25 5120 Toolkit agservice.py:197 INFO SetStream: new configuration, set everything new! 07/04/07 11:53:25 5120 Toolkit agservice.py:210 DEBUG StreamDescription okay, assign it to service! 07/04/07 11:53:25 5120 Toolkit audioservice.py:292 INFO Stop service 07/04/07 11:53:25 5120 Toolkit audioservice.py:303 INFO Executing rat-kill 07/04/07 11:53:25 5120 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 07/04/07 11:53:25 5120 ProcessManager processmanager.py:75 DEBUG Create process returns: (, , 4100, 4164) 07/04/07 11:53:26 5120 ProcessManager processmanager.py:138 ERROR couldn't shutdown process : (1444, 'PostThreadMessage', 'Invalid thread identifier.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 131, in TerminateProcess win32api.PostThreadMessage(self.threadid[pid], 18, 0, 0) error: (1444, 'PostThreadMessage', 'Invalid thread identifier.') 07/04/07 11:53:26 5120 ProcessManager processmanager.py:145 ERROR couldn't terminate process : (5, 'TerminateProcess', 'Access is denied.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 142, in TerminateProcess win32process.TerminateProcess(pid, 0) error: (5, 'TerminateProcess', 'Access is denied.') 07/04/07 11:53:26 5120 Toolkit audioservice.py:277 INFO Starting AudioService 07/04/07 11:53:26 5120 Toolkit audioservice.py:278 INFO executable = C:\Documents and Settings\zzalsar4\Application Data\AccessGrid3\local_services\AudioService\rat.exe 07/04/07 11:53:26 5120 Toolkit audioservice.py:280 INFO options = ['-C', u'AGSC Venue Server Lobby', '-S', u'8258014916e03315ba54cd300bfe16d9', '-f', 'L16-16K-Mono', '-t', '127', u'224.2.240.144/63466'] 07/04/07 11:53:26 5120 ProcessManager processmanager.py:62 DEBUG Creating process: C:\Documents and Settings\zzalsar4\Application Data\AccessGrid3\local_services\AudioService\rat.exe -C "AGSC Venue Server Lobby" -S 8258014916e03315ba54cd300bfe16d9 -f L16-16K-Mono -t 127 224.2.240.144/63466 07/04/07 11:53:26 5120 ProcessManager processmanager.py:100 ERROR process creation failed: (193, 'CreateProcess', '%1 is not a valid Win32 application.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 73, in StartProcess startup_info) error: (193, 'CreateProcess', '%1 is not a valid Win32 application.') 07/04/07 11:53:26 4608 Toolkit agservice.py:239 INFO AGService.SetEnabled : enabled = 0 07/04/07 11:53:26 4608 Toolkit agservice.py:245 INFO Stopping service 07/04/07 11:53:26 4608 Toolkit audioservice.py:292 INFO Stop service 07/04/07 11:53:26 4608 Toolkit audioservice.py:303 INFO Executing rat-kill 07/04/07 11:53:26 4608 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 07/04/07 11:53:26 4608 ProcessManager processmanager.py:75 DEBUG Create process returns: (, , 5288, 5644) 07/04/07 11:53:26 4608 ProcessManager processmanager.py:138 ERROR couldn't shutdown process : (1444, 'PostThreadMessage', 'Invalid thread identifier.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 131, in TerminateProcess win32api.PostThreadMessage(self.threadid[pid], 18, 0, 0) error: (1444, 'PostThreadMessage', 'Invalid thread identifier.') 07/04/07 11:53:26 4608 ProcessManager processmanager.py:145 ERROR couldn't terminate process : (5, 'TerminateProcess', 'Access is denied.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 142, in TerminateProcess win32process.TerminateProcess(pid, 0) error: (5, 'TerminateProcess', 'Access is denied.') 07/04/07 11:53:29 5644 Toolkit agservice.py:183 INFO SetStream: [consumer, audio, 825801490a1c0e7c6fb20168de822308, L16, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, L16, 8000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, L8, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, L8, 8000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, PCMU, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, PCMU, 8000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, GSM, 16000, 1, None, None, consumer, audio, 825801490a1c0e7c6fb20168de822308, GSM, 8000, 1, None, None, producer, audio, 825801490a1c0e7c6fb20168de822308, L16, 16000, 1, None, None] 224.2.240.144 63466 07/04/07 11:53:29 5644 Toolkit agservice.py:194 INFO SetStream: ignoring trivial re-configuration 07/04/07 11:53:29 3284 Toolkit agservice.py:239 INFO AGService.SetEnabled : enabled = 1 07/04/07 11:53:29 3284 Toolkit agservice.py:248 INFO Starting service 07/04/07 11:53:29 3284 Toolkit audioservice.py:277 INFO Starting AudioService 07/04/07 11:53:29 3284 Toolkit audioservice.py:278 INFO executable = C:\Documents and Settings\zzalsar4\Application Data\AccessGrid3\local_services\AudioService\rat.exe 07/04/07 11:53:29 3284 Toolkit audioservice.py:280 INFO options = ['-C', u'AGSC Venue Server Lobby', '-S', u'8258014916e03315ba54cd300bfe16d9', '-f', 'L16-16K-Mono', '-t', '127', u'224.2.240.144/63466'] 07/04/07 11:53:29 3284 ProcessManager processmanager.py:62 DEBUG Creating process: C:\Documents and Settings\zzalsar4\Application Data\AccessGrid3\local_services\AudioService\rat.exe -C "AGSC Venue Server Lobby" -S 8258014916e03315ba54cd300bfe16d9 -f L16-16K-Mono -t 127 224.2.240.144/63466 07/04/07 11:53:29 3284 ProcessManager processmanager.py:100 ERROR process creation failed: (193, 'CreateProcess', '%1 is not a valid Win32 application.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 73, in StartProcess startup_info) error: (193, 'CreateProcess', '%1 is not a valid Win32 application.') 07/04/07 11:53:58 2980 Toolkit audioservice.py:292 INFO Stop service 07/04/07 11:53:58 2980 Toolkit audioservice.py:303 INFO Executing rat-kill 07/04/07 11:53:58 2980 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 07/04/07 11:53:58 2980 ProcessManager processmanager.py:75 DEBUG Create process returns: (, , 4448, 5796) 07/04/07 11:53:59 2980 ProcessManager processmanager.py:138 ERROR couldn't shutdown process : (1444, 'PostThreadMessage', 'Invalid thread identifier.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 131, in TerminateProcess win32api.PostThreadMessage(self.threadid[pid], 18, 0, 0) error: (1444, 'PostThreadMessage', 'Invalid thread identifier.') 07/04/07 11:53:59 2980 ProcessManager processmanager.py:145 ERROR couldn't terminate process : (5, 'TerminateProcess', 'Access is denied.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 142, in TerminateProcess win32process.TerminateProcess(pid, 0) error: (5, 'TerminateProcess', 'Access is denied.') 07/04/07 11:53:59 888 Toolkit agservice.py:263 INFO Shut service down 07/04/07 11:53:59 888 Toolkit audioservice.py:292 INFO Stop service 07/04/07 11:53:59 888 Toolkit audioservice.py:303 INFO Executing rat-kill 07/04/07 11:53:59 888 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 07/04/07 11:53:59 888 ProcessManager processmanager.py:75 DEBUG Create process returns: (, , 1760, 3248) 07/04/07 11:53:59 888 ProcessManager processmanager.py:138 ERROR couldn't shutdown process : (1444, 'PostThreadMessage', 'Invalid thread identifier.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 131, in TerminateProcess win32api.PostThreadMessage(self.threadid[pid], 18, 0, 0) error: (1444, 'PostThreadMessage', 'Invalid thread identifier.') 07/04/07 11:53:59 888 ProcessManager processmanager.py:145 ERROR couldn't terminate process : (5, 'TerminateProcess', 'Access is denied.') Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Platform\win32\ProcessManager.py", line 142, in TerminateProcess win32process.TerminateProcess(pid, 0) error: (5, 'TerminateProcess', 'Access is denied.')