03/18/08 14:30:50 1568 Toolkit toolkit.py:691 INFO Service init: have profile None 03/18/08 14:30:50 1568 Toolkit toolkit.py:716 INFO Service Initialization Complete. 03/18/08 14:30:50 1568 Toolkit venueserver3.py:102 INFO VenueServer running using hostname: Server1.domain 03/18/08 14:30:50 1568 Toolkit venueserver3.py:105 INFO Running in secure mode 03/18/08 14:30:50 1568 CertificateManager certificatemanager.py:212 DEBUG Opened repository C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo 03/18/08 14:30:50 1568 Toolkit toolkit.py:472 INFO Initialized certificate manager. 03/18/08 14:30:50 1568 CertificateManager certificatemanager.py:575 DEBUG Configuring standard environment 03/18/08 14:30:50 1568 CertificateManager certificatemanager.py:625 DEBUG Using default identity /O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/Server1.domain 03/18/08 14:30:50 1568 Toolkit toolkit.py:262 INFO Using unencrypted certificate: C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo\certificates\a3cd7ecc7c3aed8edc15fa9429b88151\98faf736b96e264645ff747fb20c7e1d\cert.pem C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo\privatekeys\375a5d52bfaeb2e7937c4b0007dcc85b.pem 03/18/08 14:30:50 1568 VenueServer venueserver.py:182 DEBUG VenueServer initializing authorization manager. 03/18/08 14:30:50 1568 VenueServer venueserver.py:503 DEBUG Reading authorization policy. 03/18/08 14:30:50 1568 Toolkit toolkit.py:262 INFO Using unencrypted certificate: C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo\certificates\a3cd7ecc7c3aed8edc15fa9429b88151\98faf736b96e264645ff747fb20c7e1d\cert.pem C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo\privatekeys\375a5d52bfaeb2e7937c4b0007dcc85b.pem 03/18/08 14:30:50 1568 FTPSServer ftpsserver.py:40 DEBUG FTPSServer __init__: path=Data hostname=Server1.domain port=8006 03/18/08 14:30:50 1568 FTPSServer ftpsserver.py:77 DEBUG Entered FTPSServer.run_in_thread 03/18/08 14:30:50 376 FTPSServer ftpsserver.py:57 DEBUG Entered FTPSServer.run 03/18/08 14:30:50 1568 VenueServer venueserver.py:1353 DEBUG Register service 03/18/08 14:30:50 376 FTPSServer ftps_server.py:338 INFO M2Crypto (Medusa) FTP/TLS server started at Tue Mar 18 14:30:50 2008 Authz: Hostname: Server1.domain Port: 8006 03/18/08 14:30:50 1568 VenueServer venueserver.py:1353 DEBUG Register service 03/18/08 14:30:50 1568 VenueServer venueserver.py:537 DEBUG Reading persisted Venues from: VenueServer.dat 03/18/08 14:30:50 1568 VenueServer venueserver.py:542 DEBUG Loading Venue: 0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 VenueServer venue.py:344 DEBUG ------------ STARTING VENUE 03/18/08 14:30:50 1568 VenueServer venue.py:383 INFO Venue URI https://Server1.domain:8000/Venues/0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 VenueServer venue.py:393 DEBUG data store location: Data 03/18/08 14:30:50 1568 DataStore datastore.py:227 DEBUG __init__: Datastore path is Data\0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 DataStore datastore.py:266 DEBUG =================== ENTERED LoadPersistentInfo ================== 03/18/08 14:30:50 1568 DataStore datastore.py:267 DEBUG Reading persisted data from: Data\0a7bad1002441328bdb786f05c4c4b10\DataStore.dat 03/18/08 14:30:50 1568 DataStore datastore.py:272 DEBUG Loading a directory in root-dir! 03/18/08 14:30:50 1568 DataStore datastore.py:282 DEBUG LoadPersistentInfo: Server Path: Data\0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 DataStore datastore.py:286 DEBUG Getting file list of ./Data\0a7bad1002441328bdb786f05c4c4b10\ 03/18/08 14:30:50 1568 DataStore datastore.py:296 DEBUG LOADPERSISTENTINFO: Path to use is C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Data\0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 DataStore datastore.py:373 DEBUG =================== EXITED LoadPersistentInfo ================== 03/18/08 14:30:50 1568 FTPSServer ftpsserver.py:92 DEBUG Entered FTPSServer.GetUploadDescriptor 03/18/08 14:30:50 1568 FTPSServer ftpsserver.py:97 DEBUG descriptor = ftps://Server1.domain:8006/0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 VenueServer venue.py:417 INFO Have upload url: ftps://Server1.domain:8006/0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 EventService groupmsgservice.py:102 INFO connectionMade None:None 03/18/08 14:30:50 1568 EventService groupmsgservice.py:149 INFO addedConnection None:None id:0a7bad100d5813372402a06f3075eab1 03/18/08 14:30:50 1568 EventClient insecurevenueeventclient.py:60 INFO BaseVenueEventClient made connection. 03/18/08 14:30:50 1568 VenueServer venue.py:1156 DEBUG Calling SetConnections. 03/18/08 14:30:50 1568 VenueServer venueserver.py:941 INFO initializing with default policy 03/18/08 14:30:50 1568 Security authorizationmanager.py:516 INFO Tried to add role that already exists 03/18/08 14:30:50 1568 Security authorizationmanager.py:516 INFO Tried to add role that already exists 03/18/08 14:30:50 1568 Security authorizationmanager.py:516 INFO Tried to add role that already exists 03/18/08 14:30:50 1568 Security authorizationmanager.py:516 INFO Tried to add role that already exists 03/18/08 14:30:50 1568 Security authorizationmanager.py:516 INFO Tried to add role that already exists 03/18/08 14:30:50 1568 VenueServer venue.py:509 INFO Building auth policy. 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: Enter 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: Exit 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetStreams 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: UpdateLifetime 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: NegotiateCapabilities 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetStaticStreams 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetUploadDescriptor 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AsVenueDescription 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetRolesForSubject 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: CreateApplication 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: UpdateApplication 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: DestroyApplication 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: UpdateClientProfile 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: UpdateService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetServices 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddData 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: UpdateData 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveData 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetDataStoreInformation 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetDataDescriptions 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddNetworkService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveNetworkService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetNetworkServices 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetClients 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetCachedProfiles 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddConnection 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveConnection 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetConnections 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: SetConnections 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetEncryptMedia 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetDescription 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetName 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetApplication 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetApplications 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddNetworkLocationToStream 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveNetworkLocationFromStream 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetEventServiceLocation 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: DetermineSubjectRoles 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddNetService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveNetService 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: IsAuthorized 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: IsValid 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AllocateMulticastLocation 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RecycleMulticastLocation 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetState 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: AddDir 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetDescById 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: RemoveDir 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: HDDump 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetVersion 03/18/08 14:30:50 1568 Security authorizationmanager.py:430 ERROR Coudn't find action: GetDataSize 03/18/08 14:30:50 1568 VenueServer venueserver.py:952 INFO auth policy ? 0 03/18/08 14:30:50 1568 Security authorizationmanager.py:568 DEBUG Adding Subject: /O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/Server1.domain to Role: Administrators 03/18/08 14:30:50 1568 Security authorizationmanager.py:302 DEBUG RequireIdentification: 0 03/18/08 14:30:50 1568 VenueServer venueserver.py:710 DEBUG No applications to load for Venue 0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 VenueServer venueserver.py:290 DEBUG CFG: Default Venue: 0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 VenueServer venueserver.py:292 DEBUG Setting default venue. 03/18/08 14:30:50 1568 VenueServer venueserver.py:1147 INFO Setting default venue; oid=0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:30:50 1568 VenueServer venueserver.py:336 WARNING Performance data configuration incorrect. 03/18/08 14:30:50 4028 VenueServer venueserver.py:829 INFO Checkpoint starting at: Tue Mar 18 14:30:50 2008 03/18/08 14:30:50 1568 VenueServer venueserver.py:373 DEBUG Default Subject: /O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/Server1.domain 03/18/08 14:30:50 1568 Security authorizationmanager.py:568 DEBUG Adding Subject: /O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/Server1.domain to Role: Administrators 03/18/08 14:30:50 1568 Security authorizationmanager.py:580 WARNING Not adding subject /O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/Server1.domain to role Administrators, it's already there. 03/18/08 14:30:50 1568 Toolkit venueserver3.py:121 DEBUG Starting Hosting Environment. 03/18/08 14:30:50 4028 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:30:50 4028 VenueServer venueserver.py:861 INFO Checkpointing completed at: Tue Mar 18 14:30:50 2008 03/18/08 14:30:57 1492 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:30:57 1492 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 03/18/08 14:30:57 1492 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 03/18/08 14:30:57 1492 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:30:57 1492 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:30:57 1492 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 03/18/08 14:30:57 1492 VenueServer venue.py:966 DEBUG UpdateLifetime: connection not found: cid=0a7bad100ddc1336dfc8a357b4da0038 03/18/08 14:31:02 616 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:02 616 VenueServer venue.py:481 INFO authorizing subject None for action urn:#Exit 03/18/08 14:31:02 616 Security authorizationmanager.py:270 DEBUG Authorizing action Exit for None 03/18/08 14:31:02 616 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:02 616 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:31:02 616 VenueServer venue.py:488 INFO User None authorized for action Exit 03/18/08 14:31:02 616 VenueServer venue.py:1676 DEBUG Called Venue Exit on 0a7bad100ddc1336dfc8a357b4da0038 03/18/08 14:31:02 616 VenueServer venue.py:1681 ERROR Exit: User not found! None 03/18/08 14:31:02 616 Usage venue.py:1682 INFO "Exit","0","Server1 Venue Server","0a7bad1002441328bdb786f05c4c4b10" 03/18/08 14:31:02 616 VenueServer venue.py:1687 ERROR Error in usage logging Traceback (most recent call last): File "C:\Python24\lib\site-packages\AccessGrid3\AccessGrid\Venue.py", line 1683, in Exit raise ClientNotFound ClientNotFound 03/18/08 14:31:02 616 VenueServer venue.py:1062 DEBUG Called RemoveUser on 0a7bad100ddc1336dfc8a357b4da0038 03/18/08 14:31:02 616 VenueServer venue.py:1075 WARNING RemoveUser: Tried to remove a client that doesn't exist 03/18/08 14:31:02 616 Usage venue.py:1076 INFO "RemoveUser","DN Unavailable","Server1 Venue Server","0a7bad1002441328bdb786f05c4c4b10" 03/18/08 14:31:54 1460 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:54 1460 VenueServer venue.py:481 INFO authorizing subject None for action urn:#Enter 03/18/08 14:31:54 1460 Security authorizationmanager.py:270 DEBUG Authorizing action Enter for None 03/18/08 14:31:54 1460 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:54 1460 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:31:54 1460 VenueServer venue.py:488 INFO User None authorized for action Enter 03/18/08 14:31:54 1460 VenueServer venue.py:1177 DEBUG Enter called. 03/18/08 14:31:54 1460 VenueServer venue.py:1180 DEBUG Enter: Assigning connection id: 0a7bad100d5813373d31ebbc9916f498 03/18/08 14:31:54 1460 Usage venue.py:1193 INFO "Enter","","Server1 Venue Server","0a7bad1002441328bdb786f05c4c4b10" 03/18/08 14:31:54 1460 VenueServer venue.py:1194 DEBUG Current users: 03/18/08 14:31:54 1460 VenueServer venue.py:1196 DEBUG VenueClientState(name=Teset connectionId=0a7bad100d5813373d31ebbc9916f498) 03/18/08 14:31:54 1460 VenueServer venue.py:1197 DEBUG Enter: Distribute enter event 03/18/08 14:31:54 1460 VenueServer venue.py:659 DEBUG List of DataDescriptions 03/18/08 14:31:54 1460 VenueServer venue.py:1208 DEBUG state: 03/18/08 14:31:54 1432 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:54 1432 VenueServer venue.py:481 INFO authorizing subject None for action urn:#GetState 03/18/08 14:31:54 1432 Security authorizationmanager.py:270 DEBUG Authorizing action GetState for None 03/18/08 14:31:54 1432 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:54 1432 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:31:54 1432 VenueServer venue.py:488 INFO User None authorized for action GetState 03/18/08 14:31:54 1432 VenueServer venue.py:659 DEBUG List of DataDescriptions 03/18/08 14:31:54 1432 FTPSServer ftpsserver.py:92 DEBUG Entered FTPSServer.GetUploadDescriptor 03/18/08 14:31:54 1432 FTPSServer ftpsserver.py:97 DEBUG descriptor = ftps://Server1.domain:8006/0a7bad1002441328bdb786f05c4c4b10 03/18/08 14:31:55 696 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:55 696 VenueServer venue.py:481 INFO authorizing subject None for action urn:#NegotiateCapabilities 03/18/08 14:31:55 696 Security authorizationmanager.py:270 DEBUG Authorizing action NegotiateCapabilities for None 03/18/08 14:31:55 696 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:55 696 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:31:55 696 VenueServer venue.py:488 INFO User None authorized for action NegotiateCapabilities 03/18/08 14:31:55 696 VenueServer venue.py:839 DEBUG negotiate capabilities 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L8, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L8, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, PCMU, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, PCMU, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, GSM, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, 0a7bad1b22f413375b14012755999ec0, GSM, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: producer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, video, 0a7bad1b1a2413375b410124819d05ee, H261, 90000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: producer, Beacon, 1, ANY, 0, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, Beacon, 1, ANY, 0, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:893 DEBUG Stream doesn't exist create new one! 03/18/08 14:31:55 696 VenueServer venue.py:898 DEBUG Stream type: video! 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, video, 0a7bad1b1a2413375b410124819d05ee, H261, 90000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:915 DEBUG Stream prefers multicast! 03/18/08 14:31:55 696 VenueServer venue.py:918 DEBUG Stream address: 224.2.212.95 : 52490 03/18/08 14:31:55 696 VenueServer venue.py:928 DEBUG added user as producer of non-existent stream 03/18/08 14:31:55 696 VenueServer venue.py:2409 DEBUG * * * Added stream producer 0a7bad100d5813373d31ebbc9916f498 03/18/08 14:31:55 696 VenueServer venue.py:933 DEBUG sending AddStream event; stream=StreamDescription: {'description': None, 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [consumer, video, 0a7bad1b1a2413375b410124819d05ee, H261, 90000, 1, None, None], 'static': 0, 'location': multicast 224.2.212.95 52490 127, 'id': '0a7bad100d5813373d5345684d19f8c9', 'networkLocations': [multicast 224.2.212.95 52490 127], 'name': 'Server1 Venue Server'} 03/18/08 14:31:55 696 NetworkServicesManager networkservicesmanager.py:88 DEBUG ResolveMismatch: Match streams to capabilities 03/18/08 14:31:55 696 NetworkServicesManager networkservicesmanager.py:109 DEBUG ResolveMismatch: There are no network services available, ignore matching 03/18/08 14:31:55 696 VenueServer venue.py:893 DEBUG Stream doesn't exist create new one! 03/18/08 14:31:55 696 VenueServer venue.py:898 DEBUG Stream type: audio! 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L8, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, L8, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, PCMU, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, PCMU, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, GSM, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, audio, 0a7bad1b22f413375b14012755999ec0, GSM, 8000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: producer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 16000, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:915 DEBUG Stream prefers multicast! 03/18/08 14:31:55 696 VenueServer venue.py:918 DEBUG Stream address: 224.2.240.126 : 49836 03/18/08 14:31:55 696 VenueServer venue.py:928 DEBUG added user as producer of non-existent stream 03/18/08 14:31:55 696 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.240.126 224.2.212.95 03/18/08 14:31:55 696 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 49836 52490 03/18/08 14:31:55 696 VenueServer venue.py:2409 DEBUG * * * Added stream producer 0a7bad100d5813373d31ebbc9916f498 03/18/08 14:31:55 696 VenueServer venue.py:933 DEBUG sending AddStream event; stream=StreamDescription: {'description': None, 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [consumer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 16000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 8000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, L8, 16000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, L8, 8000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, PCMU, 16000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, PCMU, 8000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, GSM, 16000, 1, None, None, consumer, audio, 0a7bad1b22f413375b14012755999ec0, GSM, 8000, 1, None, None, producer, audio, 0a7bad1b22f413375b14012755999ec0, L16, 16000, 1, None, None], 'static': 0, 'location': multicast 224.2.240.126 49836 127, 'id': '0a7bad100d5813373d59bbb25fd4a750', 'networkLocations': [multicast 224.2.240.126 49836 127], 'name': 'Server1 Venue Server'} 03/18/08 14:31:55 696 NetworkServicesManager networkservicesmanager.py:88 DEBUG ResolveMismatch: Match streams to capabilities 03/18/08 14:31:55 696 NetworkServicesManager networkservicesmanager.py:109 DEBUG ResolveMismatch: There are no network services available, ignore matching 03/18/08 14:31:55 696 VenueServer venue.py:893 DEBUG Stream doesn't exist create new one! 03/18/08 14:31:55 696 VenueServer venue.py:898 DEBUG Stream type: Beacon! 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: producer, Beacon, 1, ANY, 0, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:909 DEBUG Caps: consumer, Beacon, 1, ANY, 0, 1, None, None 03/18/08 14:31:55 696 VenueServer venue.py:915 DEBUG Stream prefers multicast! 03/18/08 14:31:55 696 VenueServer venue.py:918 DEBUG Stream address: 224.2.149.65 : 55850 03/18/08 14:31:55 696 VenueServer venue.py:928 DEBUG added user as producer of non-existent stream 03/18/08 14:31:55 696 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.149.65 224.2.212.95 03/18/08 14:31:55 696 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 55850 52490 03/18/08 14:31:55 696 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.149.65 224.2.240.126 03/18/08 14:31:55 696 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 55850 49836 03/18/08 14:31:55 696 VenueServer venue.py:2409 DEBUG * * * Added stream producer 0a7bad100d5813373d31ebbc9916f498 03/18/08 14:31:55 696 VenueServer venue.py:933 DEBUG sending AddStream event; stream=StreamDescription: {'description': None, 'encryptionKey': None, 'uri': None, 'encryptionFlag': 0, 'capability': [producer, Beacon, 1, ANY, 0, 1, None, None, consumer, Beacon, 1, ANY, 0, 1, None, None], 'static': 0, 'location': multicast 224.2.149.65 55850 127, 'id': '0a7bad100d5813373d63918818940e52', 'networkLocations': [multicast 224.2.149.65 55850 127], 'name': 'Server1 Venue Server'} 03/18/08 14:31:55 420 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:55 420 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 03/18/08 14:31:55 420 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 03/18/08 14:31:55 420 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:55 420 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:31:55 420 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 03/18/08 14:31:55 420 VenueServer venue.py:963 DEBUG UpdateLifetime: 0a7bad100d5813373d31ebbc9916f498 Teset ; next heartbeat by 36 03/18/08 14:31:55 1272 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:55 1272 VenueServer venue.py:481 INFO authorizing subject None for action urn:#GetConnections 03/18/08 14:31:55 1272 Security authorizationmanager.py:270 DEBUG Authorizing action GetConnections for None 03/18/08 14:31:55 1272 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:31:55 1272 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:31:55 1272 VenueServer venue.py:488 INFO User None authorized for action GetConnections 03/18/08 14:31:55 1568 Toolkit toolkit.py:262 INFO Using unencrypted certificate: C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo\certificates\a3cd7ecc7c3aed8edc15fa9429b88151\98faf736b96e264645ff747fb20c7e1d\cert.pem C:\Documents and Settings\AccessGrid\Application Data\AccessGrid3\Config\certRepo\privatekeys\375a5d52bfaeb2e7937c4b0007dcc85b.pem 03/18/08 14:31:55 1568 EventService groupmsgservice.py:102 INFO connectionMade Client1.domain:2123 03/18/08 14:31:55 1568 EventService groupmsgservice.py:56 INFO beta2+: groupid=0a7bad1002441328bdb786f05c4c4b10; connectionid=0a7bad100d5813373d31ebbc9916f498 03/18/08 14:31:55 1568 EventService groupmsgservice.py:149 INFO addedConnection Client1.domain:2123 id:0a7bad100d5813373d8b16cab66915c9 03/18/08 14:32:05 3208 VenueServer venue.py:741 DEBUG CleanupClients: client 0a7bad100d5813373d31ebbc9916f498 Teset timeout=1205814835 03/18/08 14:32:05 3208 VenueServer venue.py:244 DEBUG Client Timeout Check: 1205814835 vs 1205814725 03/18/08 14:32:20 3208 VenueServer venue.py:741 DEBUG CleanupClients: client 0a7bad100d5813373d31ebbc9916f498 Teset timeout=1205814835 03/18/08 14:32:20 3208 VenueServer venue.py:244 DEBUG Client Timeout Check: 1205814835 vs 1205814740 03/18/08 14:32:31 1368 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:32:31 1368 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 03/18/08 14:32:31 1368 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 03/18/08 14:32:31 1368 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:32:31 1368 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:32:31 1368 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 03/18/08 14:32:31 1368 VenueServer venue.py:963 DEBUG UpdateLifetime: 0a7bad100d5813373d31ebbc9916f498 Teset ; next heartbeat by 36 03/18/08 14:32:35 3208 VenueServer venue.py:741 DEBUG CleanupClients: client 0a7bad100d5813373d31ebbc9916f498 Teset timeout=1205814871 03/18/08 14:32:35 3208 VenueServer venue.py:244 DEBUG Client Timeout Check: 1205814871 vs 1205814755 03/18/08 14:32:50 3208 VenueServer venue.py:741 DEBUG CleanupClients: client 0a7bad100d5813373d31ebbc9916f498 Teset timeout=1205814871 03/18/08 14:32:50 3208 VenueServer venue.py:244 DEBUG Client Timeout Check: 1205814871 vs 1205814770 03/18/08 14:33:05 3208 VenueServer venue.py:741 DEBUG CleanupClients: client 0a7bad100d5813373d31ebbc9916f498 Teset timeout=1205814871 03/18/08 14:33:05 3208 VenueServer venue.py:244 DEBUG Client Timeout Check: 1205814871 vs 1205814785 03/18/08 14:33:07 3140 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:33:07 3140 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 03/18/08 14:33:07 3140 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 03/18/08 14:33:07 3140 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:33:07 3140 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:33:07 3140 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 03/18/08 14:33:07 3140 VenueServer venue.py:963 DEBUG UpdateLifetime: 0a7bad100d5813373d31ebbc9916f498 Teset ; next heartbeat by 36 03/18/08 14:33:10 420 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:33:10 420 VenueServer venue.py:481 INFO authorizing subject None for action urn:#Exit 03/18/08 14:33:10 420 Security authorizationmanager.py:270 DEBUG Authorizing action Exit for None 03/18/08 14:33:10 420 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:33:10 420 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 03/18/08 14:33:10 420 VenueServer venue.py:488 INFO User None authorized for action Exit 03/18/08 14:33:10 420 VenueServer venue.py:1676 DEBUG Called Venue Exit on 0a7bad100d5813373d31ebbc9916f498 03/18/08 14:33:10 420 Usage venue.py:1685 INFO "Exit","","Server1 Venue Server","0a7bad1002441328bdb786f05c4c4b10" 03/18/08 14:33:10 420 VenueServer venue.py:1062 DEBUG Called RemoveUser on 0a7bad100d5813373d31ebbc9916f498 03/18/08 14:33:10 420 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.212.95 224.2.212.95 03/18/08 14:33:10 420 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 52490 52490 03/18/08 14:33:10 420 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.240.126 224.2.212.95 03/18/08 14:33:10 420 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 49836 52490 03/18/08 14:33:10 420 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.240.126 224.2.240.126 03/18/08 14:33:10 420 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 49836 49836 03/18/08 14:33:10 420 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.149.65 224.2.212.95 03/18/08 14:33:10 420 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 55850 52490 03/18/08 14:33:10 420 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.149.65 224.2.240.126 03/18/08 14:33:10 420 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 55850 49836 03/18/08 14:33:10 420 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.149.65 224.2.149.65 03/18/08 14:33:10 420 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 55850 55850 03/18/08 14:33:10 420 Usage venue.py:1082 INFO "RemoveUser","","Server1 Venue Server","0a7bad1002441328bdb786f05c4c4b10" 03/18/08 14:33:10 420 VenueServer venue.py:1106 DEBUG Removing single instance of user 03/18/08 14:33:10 420 VenueServer venue.py:1128 DEBUG RemoveUser: Distribute EXIT event 03/18/08 14:33:12 1568 EventService groupmsgservice.py:158 INFO removedConnection Client1.domain:2123 id:0a7bad100d5813373d8b16cab66915c9 03/18/08 14:33:34 1568 Toolkit venueserver3.py:55 INFO Caught signal, going down. 03/18/08 14:33:34 1568 Toolkit venueserver3.py:56 INFO Signal: 2 Frame: 03/18/08 14:33:34 1568 VenueServer venueserver.py:756 INFO Starting Shutdown! 03/18/08 14:33:34 1568 EventService groupmsgservice.py:158 INFO removedConnection None:None id:0a7bad100d5813372402a06f3075eab1 03/18/08 14:33:34 1568 EventClient insecurevenueeventclient.py:85 INFO BaseVenueEventClient lost connection; reason=[Failure instance: Traceback (failure with no frames): : twisted.internet.error.ConnectionDone ] 03/18/08 14:33:34 1568 VenueServer venueserver.py:776 INFO Shutdown -> Checkpointing... 03/18/08 14:33:34 1568 VenueServer venueserver.py:829 INFO Checkpoint starting at: Tue Mar 18 14:33:34 2008 03/18/08 14:33:34 1568 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning 0 03/18/08 14:33:34 1568 VenueServer venueserver.py:861 INFO Checkpointing completed at: Tue Mar 18 14:33:34 2008 03/18/08 14:33:34 1568 VenueServer venueserver.py:778 INFO done 03/18/08 14:33:34 1568 VenueServer venueserver.py:784 INFO Shutting down services... 03/18/08 14:33:34 1568 FTPSServer ftpsserver.py:85 DEBUG Entered FTPSServer.stop 03/18/08 14:33:35 1568 VenueServer venueserver.py:808 INFO done. 03/18/08 14:33:35 1568 VenueServer venueserver.py:810 INFO Shutdown Complete. 03/18/08 14:33:35 1568 Toolkit venueserver3.py:128 DEBUG After main loop! 03/18/08 14:33:35 1568 Toolkit venueserver3.py:133 DEBUG Stopped Hosting Environment, exiting. 03/18/08 14:33:36 1568 Toolkit venueserver3.py:142 DEBUG Thread <_MainThread(MainThread, started)>