I'm having problems with a new AG 3.1 installation on Windows XP. The video service works fine and the VIC window opens, but the RAT window silently fails to open. I suspect there may be a software firewall issue (the PC is controlled by the central IT organization), and have included part of the log below. Any other explanations that could account for this behavior? Thanks.
Enrique Terrazas 12/15/09 09:52:44 3724 Toolkit config.py:625 DEBUG retrieved local IP address 10.174.103.142 12/15/09 09:52:44 3724 Toolkit toolkit.py:116 DEBUG Initializing AG Toolkit version 3.1 12/15/09 09:52:44 3724 Toolkit toolkit.py:117 INFO Command and arguments: ['C:\\Documents and Settings\\VHASFCleongh\\Application Data\\AccessGrid3\\local_services\\AudioService\\AudioService.py', '--port', '65128', '--serviceManagerUri', 'http://10.174.103.142:11000/ServiceManager', '--token', 'a9fe7b6e07c05b8c0a81f8ad923c7923'] 12/15/09 09:52:44 3724 VenueClient preferences.py:188 DEBUG Preferences.LoadPreferences: open file 12/15/09 09:52:44 3724 Toolkit toolkit.py:166 INFO Logfile Name: AudioService.log 12/15/09 09:52:44 3724 Toolkit toolkit.py:691 INFO Service init: have profile None 12/15/09 09:52:44 3724 Toolkit toolkit.py:716 INFO Service Initialization Complete. 12/15/09 09:52:44 3724 Toolkit agservice.py:403 INFO Starting Service URI: http://10.174.103.142:65128/Services/AudioService.a9fe7b6e0d6c5b8c0c7ee5afb75eb272 12/15/09 09:52:44 3724 Toolkit agservice.py:413 DEBUG Registering with service manager; url=http://10.174.103.142:11000/ServiceManager 12/15/09 09:52:44 3176 Toolkit audioservice.py:356 INFO SetIdentity: VASF Lab Med [email protected] 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, L16, 16000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, L16, 8000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, L8, 16000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, L8, 8000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, PCMU, 16000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, PCMU, 8000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, GSM, 16000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service consumer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, GSM, 8000, 1, None, None 12/15/09 09:52:44 3448 Toolkit agservice.py:292 DEBUG GetDescription:Capability in Service producer, audio, a9fe7b6e0d6c5b8c0aaa012fab667ba1, L16, 16000, 1, None, None 12/15/09 09:52:51 3044 Toolkit agservice.py:246 INFO AGService.SetEnabled : enabled = 1 12/15/09 09:53:25 4160 Toolkit agservice.py:185 INFO SetStream: incoming stream [consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L8, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L8, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, PCMU, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, PCMU, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, GSM, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, GSM, 8000, 1, None, None, producer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 16000, 1, None, None] 64.54.29.247 50130 12/15/09 09:53:25 4160 Toolkit agservice.py:204 INFO SetStream: new configuration, set everything new! 12/15/09 09:53:25 4160 Toolkit agservice.py:216 DEBUG StreamDescription okay, assign it to service! 12/15/09 09:53:25 4160 Toolkit audioservice.py:297 INFO Stop service 12/15/09 09:53:25 4160 Toolkit audioservice.py:310 INFO Executing rat-kill 12/15/09 09:53:25 4160 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 12/15/09 09:53:25 4160 ProcessManager processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:716>, <PyHANDLE:724>, 4164, 4168) 12/15/09 09:53:25 4160 ProcessManager processmanager.py:138 ERROR couldn't shutdown process <PyHANDLE:716>: (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.') 12/15/09 09:53:25 4160 ProcessManager processmanager.py:145 ERROR couldn't terminate process <PyHANDLE:716>: (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.') 12/15/09 09:53:25 4160 Toolkit audioservice.py:282 INFO Starting AudioService 12/15/09 09:53:25 4160 Toolkit audioservice.py:283 INFO executable = C:\Documents and Settings\VHASFCleongh\Application Data\AccessGrid3\local_services\AudioService\rat.exe 12/15/09 09:53:25 4160 Toolkit audioservice.py:285 INFO options = ['-C', u'UCSF Lab Medicine Lobby (AG3)', '-S', u'a9fe7b6e08f45b8676055f5e77b3906d', '-f', 'L16-16K-Mono', u'64.54.29.247/50130'] 12/15/09 09:53:25 4160 ProcessManager processmanager.py:62 DEBUG Creating process: C:\Documents and Settings\VHASFCleongh\Application Data\AccessGrid3\local_services\AudioService\rat.exe -C "UCSF Lab Medicine Lobby (AG3)" -S a9fe7b6e08f45b8676055f5e77b3906d -f L16-16K-Mono 64.54.29.247/50130 12/15/09 09:53:25 4160 ProcessManager processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:708>, <PyHANDLE:720>, 4220, 4224) 12/15/09 09:53:34 4408 Toolkit agservice.py:246 INFO AGService.SetEnabled : enabled = 0 12/15/09 09:53:34 4408 Toolkit agservice.py:252 INFO Stopping service 12/15/09 09:53:34 4408 Toolkit audioservice.py:297 INFO Stop service 12/15/09 09:53:34 4408 Toolkit audioservice.py:310 INFO Executing rat-kill 12/15/09 09:53:34 4408 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 12/15/09 09:53:34 4408 ProcessManager processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:700>, <PyHANDLE:712>, 4412, 4416) 12/15/09 09:53:34 4408 ProcessManager processmanager.py:136 WARNING Couldn't terminate process <PyHANDLE:708> cleanly (258) 12/15/09 09:53:34 4408 ProcessManager processmanager.py:138 ERROR couldn't shutdown process <PyHANDLE:700>: (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.') 12/15/09 09:53:34 4408 ProcessManager processmanager.py:145 ERROR couldn't terminate process <PyHANDLE:700>: (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.') 12/15/09 09:53:36 4468 Toolkit agservice.py:185 INFO SetStream: incoming stream [consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L8, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L8, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, PCMU, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, PCMU, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, GSM, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, GSM, 8000, 1, None, None, producer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 16000, 1, None, None] 64.54.29.247 50130 12/15/09 09:53:36 4468 Toolkit agservice.py:190 INFO SetStream: current stream [consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L8, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L8, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, PCMU, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, PCMU, 8000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, GSM, 16000, 1, None, None, consumer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, GSM, 8000, 1, None, None, producer, audio, a9fe7b6e0f785b8a1aa1012cb43d435a, L16, 16000, 1, None, None] 64.54.29.247 50130 12/15/09 09:53:36 4468 Toolkit agservice.py:201 INFO SetStream: ignoring trivial re-configuration 12/15/09 09:53:36 4472 Toolkit agservice.py:246 INFO AGService.SetEnabled : enabled = 1 12/15/09 09:53:36 4472 Toolkit agservice.py:255 INFO Starting service 12/15/09 09:53:36 4472 Toolkit audioservice.py:282 INFO Starting AudioService 12/15/09 09:53:36 4472 Toolkit audioservice.py:283 INFO executable = C:\Documents and Settings\VHASFCleongh\Application Data\AccessGrid3\local_services\AudioService\rat.exe 12/15/09 09:53:36 4472 Toolkit audioservice.py:285 INFO options = ['-C', u'UCSF Lab Medicine Lobby (AG3)', '-S', u'a9fe7b6e08f45b8676055f5e77b3906d', '-f', 'L16-16K-Mono', u'64.54.29.247/50130'] 12/15/09 09:53:36 4472 ProcessManager processmanager.py:62 DEBUG Creating process: C:\Documents and Settings\VHASFCleongh\Application Data\AccessGrid3\local_services\AudioService\rat.exe -C "UCSF Lab Medicine Lobby (AG3)" -S a9fe7b6e08f45b8676055f5e77b3906d -f L16-16K-Mono 64.54.29.247/50130 12/15/09 09:53:36 4472 ProcessManager processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:700>, <PyHANDLE:696>, 4476, 4480) 12/15/09 09:53:50 4580 Toolkit audioservice.py:297 INFO Stop service 12/15/09 09:53:50 4580 Toolkit audioservice.py:310 INFO Executing rat-kill 12/15/09 09:53:50 4580 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 12/15/09 09:53:50 4580 ProcessManager processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:724>, <PyHANDLE:704>, 4584, 4588) 12/15/09 09:53:50 4580 ProcessManager processmanager.py:136 WARNING Couldn't terminate process <PyHANDLE:700> cleanly (258) 12/15/09 09:53:50 4580 ProcessManager processmanager.py:138 ERROR couldn't shutdown process <PyHANDLE:724>: (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.') 12/15/09 09:53:50 4580 ProcessManager processmanager.py:145 ERROR couldn't terminate process <PyHANDLE:724>: (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.') 12/15/09 09:53:51 4648 Toolkit agservice.py:270 INFO Shut service down 12/15/09 09:53:51 4648 Toolkit audioservice.py:297 INFO Stop service 12/15/09 09:53:51 4648 Toolkit audioservice.py:310 INFO Executing rat-kill 12/15/09 09:53:51 4648 ProcessManager processmanager.py:62 DEBUG Creating process: .\rat-kill.exe 12/15/09 09:53:51 4648 ProcessManager processmanager.py:75 DEBUG Create process returns: (<PyHANDLE:724>, <PyHANDLE:696>, 4652, 4656) 12/15/09 09:53:51 4648 ProcessManager processmanager.py:138 ERROR couldn't shutdown process <PyHANDLE:724>: (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.') 12/15/09 09:53:51 4648 ProcessManager processmanager.py:145 ERROR couldn't terminate process <PyHANDLE:724>: (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.') -- Enrique Terrazas, MD, MS Director, Laboratory Medicine Residency Program Director, NCPL Laboratories Chief, Laboratory Information Systems Associate Professor University of California, San Francisco Dept. of Laboratory Medicine, Box 0451 4-8931, 415.514.8931(voice) 3-4826, 415.353.4826(fax) 415.443.0913(pager) [email protected] CONFIDENTIALITY NOTICE: The information contained in this transmission, including any enclosed files, is confidential and intended solely for the addressee. If you are not the intended recipient, you are hereby notified that any distribution, copying, disclosure and use of, the contents of this transmission is strictly prohibited. If you have received this communication in error, please notify the sender immediately by reply email and permanently delete this message, enclosures and all copies.
