02/08/06 14:41:32 1752 Toolkit toolkit.py:639 INFO Service init: have profile None 02/08/06 14:41:32 1752 Toolkit toolkit.py:664 INFO Service Initialization Complete. 02/08/06 14:41:32 1752 Toolkit venueserver.py:93 INFO VenueServer running using hostname: skadi.cns.ualberta.ca 02/08/06 14:41:32 1752 Toolkit venueserver.py:96 INFO Running in secure mode 02/08/06 14:41:32 1752 CertificateManager certificatemanager.py:204 DEBUG Opened repository C:\Documents and Settings\jonj\Application Data\AccessGrid3\Config\certRepo 02/08/06 14:41:33 1752 Toolkit toolkit.py:420 INFO Initialized certificate manager. 02/08/06 14:41:33 1752 CertificateManager certificatemanager.py:529 DEBUG Configuring standard environment 02/08/06 14:41:33 1752 CertificateManager certificatemanager.py:580 DEBUG Using default identity /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson 02/08/06 14:41:33 1752 CertificateManager certificatemanager.py:529 DEBUG Configuring standard environment 02/08/06 14:41:33 1752 CertificateManager certificatemanager.py:580 DEBUG Using default identity /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson 02/08/06 14:41:33 1752 CertificateManager certificatemanager.py:959 DEBUG done, success=1 02/08/06 14:41:33 1752 Toolkit toolkit.py:438 INFO Initialized certificate manager UI. 02/08/06 14:41:41 1752 VenueServer venueserver.py:176 DEBUG VenueServer initializing authorization manager. 02/08/06 14:41:41 1752 VenueServer venueserver.py:439 DEBUG Reading authorization policy. 02/08/06 14:41:41 1752 FTPSServer ftpsserver.py:40 DEBUG FTPSServer __init__: path=Data hostname=skadi.cns.ualberta.ca port=8006 02/08/06 14:41:41 1752 FTPSServer ftpsserver.py:77 DEBUG Entered FTPSServer.run_in_thread 02/08/06 14:41:41 1616 FTPSServer ftpsserver.py:57 DEBUG Entered FTPSServer.run 02/08/06 14:41:41 1752 VenueServer venueserver.py:1249 DEBUG Register service 02/08/06 14:41:41 1616 FTPSServer ftps_server.py:338 INFO M2Crypto (Medusa) FTP/TLS server started at Wed Feb 08 14:41:41 2006 Authz: Hostname: skadi Port: 8006 02/08/06 14:41:41 1752 VenueServer venueserver.py:1249 DEBUG Register service 02/08/06 14:41:41 1752 VenueServer venueserver.py:473 DEBUG Reading persisted Venues from: VenueServer.dat 02/08/06 14:41:41 1752 VenueServer venueserver.py:478 DEBUG Loading Venue: 818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 VenueServer venue.py:366 DEBUG ------------ STARTING VENUE 02/08/06 14:41:41 1752 VenueServer venue.py:405 INFO Venue URI https://skadi.cns.ualberta.ca:8000/Venues/818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 VenueServer venue.py:415 DEBUG data store location: Data 02/08/06 14:41:41 1752 DataStore datastore.py:243 DEBUG Reading persisted data from: Data\818008a00710077ad02acdea5879fe24\DataStore.dat 02/08/06 14:41:41 1752 FTPSServer ftpsserver.py:92 DEBUG Entered FTPSServer.GetUploadDescriptor 02/08/06 14:41:41 1752 FTPSServer ftpsserver.py:97 DEBUG descriptor = ftps://skadi.cns.ualberta.ca:8006/818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 VenueServer venue.py:439 INFO Have upload url: ftps://skadi.cns.ualberta.ca:8006/818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 EventService groupmsgservice.py:90 INFO connectionMade None:None id:818008a009f8078fae825a55b7ba6b4c 02/08/06 14:41:41 1752 EventService groupmsgservice.py:137 INFO addedConnection None:None id:818008a009f8078fae825a55b7ba6b4c 02/08/06 14:41:41 1752 EventClient insecurevenueeventclient.py:60 INFO BaseVenueEventClient made connection. 02/08/06 14:41:41 1752 VenueServer venue.py:1083 DEBUG Calling SetConnections. 02/08/06 14:41:41 1752 Security authorizationmanager.py:468 DEBUG Adding Subject: /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson to Role: Administrators 02/08/06 14:41:41 1752 Security authorizationmanager.py:480 WARNING Not adding subject /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson to role Administrators, it's already there. 02/08/06 14:41:41 1752 VenueServer venueserver.py:638 DEBUG No applications to load for Venue 818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 VenueServer venueserver.py:284 DEBUG CFG: Default Venue: 818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 VenueServer venueserver.py:286 DEBUG Setting default venue. 02/08/06 14:41:41 1752 VenueServer venueserver.py:1043 INFO Setting default venue; oid=818008a00710077ad02acdea5879fe24 02/08/06 14:41:41 1752 VenueServer venueserver.py:330 WARNING Performance data configuration incorrect. 02/08/06 14:41:41 2304 VenueServer venueserver.py:757 INFO Checkpoint starting at: Wed Feb 08 14:41:41 2006 02/08/06 14:41:41 2304 VenueServer venue.py:541 WARNING services not being persisted yet 02/08/06 14:41:41 1752 VenueServer venueserver.py:367 DEBUG Default Subject: /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson 02/08/06 14:41:41 1752 Security authorizationmanager.py:468 DEBUG Adding Subject: /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson to Role: Administrators 02/08/06 14:41:41 1752 Security authorizationmanager.py:480 WARNING Not adding subject /O=Access Grid/OU=agdev-ca.mcs.anl.gov/OU=ualberta.ca/CN=Jon Johansson to role Administrators, it's already there. 02/08/06 14:41:41 1752 Toolkit venueserver.py:117 DEBUG Starting Hosting Environment. 02/08/06 14:41:41 2304 VenueServer venueserver.py:789 INFO Checkpointing completed at: Wed Feb 08 14:41:41 2006 02/08/06 14:46:41 2304 VenueServer venueserver.py:757 INFO Checkpoint starting at: Wed Feb 08 14:46:41 2006 02/08/06 14:46:41 2304 VenueServer venue.py:541 WARNING services not being persisted yet 02/08/06 14:46:41 2304 VenueServer venueserver.py:789 INFO Checkpointing completed at: Wed Feb 08 14:46:41 2006 02/08/06 14:51:41 2304 VenueServer venueserver.py:757 INFO Checkpoint starting at: Wed Feb 08 14:51:41 2006 02/08/06 14:51:41 2304 VenueServer venue.py:541 WARNING services not being persisted yet 02/08/06 14:51:41 2304 VenueServer venueserver.py:789 INFO Checkpointing completed at: Wed Feb 08 14:51:41 2006 02/08/06 14:56:41 2304 VenueServer venueserver.py:757 INFO Checkpoint starting at: Wed Feb 08 14:56:41 2006 02/08/06 14:56:41 2304 VenueServer venue.py:541 WARNING services not being persisted yet 02/08/06 14:56:41 2304 VenueServer venueserver.py:789 INFO Checkpointing completed at: Wed Feb 08 14:56:41 2006 02/08/06 14:57:46 1460 Security authorizationmanager.py:201 DEBUG Authorizing action Enter for None 02/08/06 14:57:46 1460 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:57:46 1460 VenueServer venue.py:1104 DEBUG Enter called. 02/08/06 14:57:46 1460 VenueServer venue.py:1109 DEBUG Enter: Assigning connection id: 818008a009f80791279535a3925199cc 02/08/06 14:57:46 1460 VenueServer venue.py:494 WARNING UpdateProfileCache: InvalidProfile when storing a venue user's profile in the cache. 02/08/06 14:57:46 1460 Usage venue.py:1122 INFO "Enter","","Venue Server Lobby","818008a00710077ad02acdea5879fe24" 02/08/06 14:57:46 1460 VenueServer venue.py:1123 DEBUG Current users: 02/08/06 14:57:46 1460 VenueServer venue.py:1125 DEBUG VenueClientState(name=Jon Johansson connectionId=818008a009f80791279535a3925199cc) 02/08/06 14:57:46 1460 VenueServer venue.py:1126 DEBUG Enter: Distribute enter event 02/08/06 14:57:46 1460 VenueServer venue.py:1137 DEBUG state: 02/08/06 14:57:46 2812 Security authorizationmanager.py:201 DEBUG Authorizing action GetState for None 02/08/06 14:57:46 2812 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:57:46 2812 FTPSServer ftpsserver.py:92 DEBUG Entered FTPSServer.GetUploadDescriptor 02/08/06 14:57:46 2812 FTPSServer ftpsserver.py:97 DEBUG descriptor = ftps://skadi.cns.ualberta.ca:8006/818008a00710077ad02acdea5879fe24 02/08/06 14:57:46 3052 Security authorizationmanager.py:201 DEBUG Authorizing action NegotiateCapabilities for None 02/08/06 14:57:46 3052 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:57:46 3052 VenueServer venue.py:797 DEBUG negotiate capabilities 02/08/06 14:57:46 3052 VenueServer venue.py:856 DEBUG added user as producer of non-existent stream 02/08/06 14:57:46 3052 VenueServer venue.py:2202 DEBUG * * * Added stream producer 818008a009f80791279535a3925199cc 02/08/06 14:57:46 3052 NetworkServicesManager networkservicesmanager.py:88 DEBUG ResolveMismatch: Match streams to capabilities 02/08/06 14:57:46 3052 NetworkServicesManager networkservicesmanager.py:109 DEBUG ResolveMismatch: There are no network services available, ignore matching 02/08/06 14:57:46 3052 VenueServer venue.py:856 DEBUG added user as producer of non-existent stream 02/08/06 14:57:46 3052 VenueServer venue.py:2306 DEBUG StreamDescriptionList.index Address 224.2.154.59 224.2.190.112 02/08/06 14:57:46 3052 VenueServer venue.py:2309 DEBUG StreamDescriptionList.index Port 57960 52910 02/08/06 14:57:46 3052 VenueServer venue.py:2202 DEBUG * * * Added stream producer 818008a009f80791279535a3925199cc 02/08/06 14:57:46 716 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:57:46 716 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:57:46 2880 Security authorizationmanager.py:201 DEBUG Authorizing action GetConnections for None 02/08/06 14:57:46 2880 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:57:46 1752 EventService groupmsgservice.py:90 INFO connectionMade 129.128.8.160:1676 id:818008a009f8079127bfdd376fba1233 02/08/06 14:57:48 1752 EventService groupmsgservice.py:137 INFO addedConnection 129.128.8.160:1676 id:818008a009f8079127bfdd376fba1233 02/08/06 14:57:56 2724 VenueServer venue.py:235 DEBUG Client Timeout Check: 1139435876 vs 1139435876 02/08/06 14:58:11 2724 VenueServer venue.py:235 DEBUG Client Timeout Check: 1139435876 vs 1139435891 02/08/06 14:58:11 2724 VenueServer venue.py:693 DEBUG Removing user 818008a009f80791279535a3925199cc at 1139435891 (Timed Out) 02/08/06 14:58:11 2724 VenueServer venue.py:994 DEBUG Called RemoveUser on 818008a009f80791279535a3925199cc 02/08/06 14:58:11 2724 VenueServer venue.py:2306 DEBUG StreamDescriptionList.index Address 224.2.190.112 224.2.190.112 02/08/06 14:58:11 2724 VenueServer venue.py:2309 DEBUG StreamDescriptionList.index Port 52910 52910 02/08/06 14:58:11 2724 VenueServer venue.py:2306 DEBUG StreamDescriptionList.index Address 224.2.154.59 224.2.190.112 02/08/06 14:58:11 2724 VenueServer venue.py:2309 DEBUG StreamDescriptionList.index Port 57960 52910 02/08/06 14:58:11 2724 VenueServer venue.py:2306 DEBUG StreamDescriptionList.index Address 224.2.154.59 224.2.154.59 02/08/06 14:58:11 2724 VenueServer venue.py:2309 DEBUG StreamDescriptionList.index Port 57960 57960 02/08/06 14:58:11 2724 Usage venue.py:1009 INFO "RemoveUser","","Venue Server Lobby","818008a00710077ad02acdea5879fe24" 02/08/06 14:58:11 2724 VenueServer venue.py:1033 DEBUG Removing single instance of user 02/08/06 14:58:11 2724 VenueServer venue.py:1055 DEBUG RemoveUser: Distribute EXIT event 02/08/06 14:58:50 496 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 496 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 1972 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 1972 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 3048 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 3048 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 2696 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 2696 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 596 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 596 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 512 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 512 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 2576 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 2576 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 1644 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 1644 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 476 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 476 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:58:50 2704 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:58:50 2704 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 2592 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 2592 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 2908 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 2908 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 2500 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 2500 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 1272 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 1272 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 308 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 308 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 1088 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 1088 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 14:59:53 1792 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 14:59:53 1792 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 15:00:55 2540 Security authorizationmanager.py:201 DEBUG Authorizing action UpdateLifetime for None 02/08/06 15:00:55 2540 Security authorizationmanager.py:213 DEBUG Accepting access from unidentified user as part of Everybody role 02/08/06 15:00:56 1752 EventService groupmsgservice.py:144 INFO removedConnection 129.128.8.160:1676 id:818008a009f8079127bfdd376fba1233 02/08/06 15:01:41 2304 VenueServer venueserver.py:757 INFO Checkpoint starting at: Wed Feb 08 15:01:41 2006 02/08/06 15:01:41 2304 VenueServer venue.py:541 WARNING services not being persisted yet 02/08/06 15:01:41 2304 VenueServer venueserver.py:789 INFO Checkpointing completed at: Wed Feb 08 15:01:41 2006 02/08/06 15:03:18 1752 Toolkit venueserver.py:50 INFO Caught signal, going down. 02/08/06 15:03:18 1752 Toolkit venueserver.py:51 INFO Signal: 2 Frame: 02/08/06 15:03:18 1752 VenueServer venueserver.py:684 INFO Starting Shutdown! 02/08/06 15:03:18 1752 EventService groupmsgservice.py:144 INFO removedConnection None:None id:818008a009f8078fae825a55b7ba6b4c 02/08/06 15:03:18 1752 EventClient insecurevenueeventclient.py:77 INFO BaseVenueEventClient lost connection. 02/08/06 15:03:18 1752 VenueServer venueserver.py:704 INFO Shutdown -> Checkpointing... 02/08/06 15:03:18 1752 VenueServer venueserver.py:757 INFO Checkpoint starting at: Wed Feb 08 15:03:18 2006 02/08/06 15:03:18 1752 VenueServer venue.py:541 WARNING services not being persisted yet 02/08/06 15:03:18 1752 VenueServer venueserver.py:789 INFO Checkpointing completed at: Wed Feb 08 15:03:18 2006 02/08/06 15:03:18 1752 VenueServer venueserver.py:706 INFO done 02/08/06 15:03:18 1752 VenueServer venueserver.py:712 INFO Shutting down services... 02/08/06 15:03:18 1752 FTPSServer ftpsserver.py:85 DEBUG Entered FTPSServer.stop 02/08/06 15:03:18 1752 VenueServer venueserver.py:736 INFO done. 02/08/06 15:03:18 1752 VenueServer venueserver.py:738 INFO Shutdown Complete. 02/08/06 15:03:18 1752 Toolkit venueserver.py:124 DEBUG After main loop! 02/08/06 15:03:18 1752 Toolkit venueserver.py:129 DEBUG Stopped Hosting Environment, exiting. 02/08/06 15:03:19 1752 Toolkit venueserver.py:138 DEBUG Thread 02/08/06 15:03:19 1752 Toolkit venueserver.py:138 DEBUG Thread <_MainThread(MainThread, started)>