would help if I attached the file... ________________________________________ From: ag-tech-boun...@lists.mcs.anl.gov [ag-tech-boun...@lists.mcs.anl.gov] On Behalf Of Vine, Derek A [derek.v...@usd.edu] Sent: Tuesday, April 27, 2010 10:23 AM To: Thomas Uram; Christoph Willing Cc: ag-t...@mcs.anl.gov Subject: Re: [AG-TECH] Problems uploading files
I am not sure if it is the same thing, but I too am having issues uploading files to our venue server, attached is the log that I sent to the list before but never heard anything back. My setup is running on Windows server 2003. Thanks, Derek Vine ________________________________________ From: ag-tech-boun...@lists.mcs.anl.gov [ag-tech-boun...@lists.mcs.anl.gov] On Behalf Of Thomas Uram [tu...@mcs.anl.gov] Sent: Tuesday, April 27, 2010 10:06 AM To: Christoph Willing Cc: ag-t...@mcs.anl.gov Subject: Re: [AG-TECH] Problems uploading files The VenueClient.log shows no evidence of a problem. I suspect a server-side failure which prevents transmission of the ADD_DATA event, which would explain the client behavior. Has anyone captured the problem in a VenueServer.log? Tom On Apr 27, 2010, at 6:42 AM, Christoph Willing wrote: > > On 27/04/2010, at 2:32 PM, John I. Quebedeaux, Jr wrote: > >> Hmmm... And ditto to Fedora 12, and 'ding' I may be having the same problem. >> When I look for the files, they appear to be '0' sized though... But I >> haven't tried a 'small' one yet. > > > Thanks for adding to the evidence John. > > It seems there are two aspects to this problem. On the server side, the file > is not received resulting in a zero size file. However I don't anymore think > this is a data size issue - it just seemed that way to me at first. The AG's > FTPSServer prepares to start but no data at all is actually exchanged between > the client and the server. > > There is a client side problem too, that even when a file has uploaded > correctly it may not appear in the venue client ui. > > Its a very weird problem. I spent most of today with an "old" system (in > which server & client sides both work perfectly) and gradually built & > installed updated packages so that they all match a current non-working > system - figuring that the fault would manifest after one of these updates, > identifying it as the culprit. "Unfortunately" the system still works at the > end of all that - it seems none of the prime suspect packages is causing the > problem. Tomorrow I'll either have to come with a new strategy or new suspect > support packages. Does anyone want to suspect, say, openssl or even python > itself? > > > chris > > >>> From: Christoph Willing <c.will...@uq.edu.au> >>> Date: Tue, 27 Apr 2010 10:04:58 +1000 >>> To: Jesus Cea Oliva <jesus....@uca.es> >>> Cc: <ag-t...@mcs.anl.gov> >>> Subject: Re: [AG-TECH] Problems uploading files >>> >>> Jesus, >>> >>> Could you submit a bug report for this problem please? You can do that >>> via the VenueClient Help menu. >>> >>> >>> I have now been able to replicate the problem on several systems - >>> Ubuntu, Slackware, Fedora & Debian. Interestingly older versions >>> running on these systems seem to work OK i.e. when a venue server runs >>> on the older systems, the problem is not evident. Unfortunately I >>> don't have resources to test all Linux systems but so far, the last >>> known versions to work correctly (a client can upload to a server >>> running on the same system) are: >>> Ubuntu Intrepid >>> Slackware 12.2 >>> Fedora - unknown exactly but the problem is evident in Fedora 11 >>> Debian - Lenny partially works >>> >>> The Debian case is interesting because a Lenny client will not upload >>> data to a Lenny server, yet a Jaunty client can upload to a Lenny >>> server (even though it can't upload to a Jaunty server). >>> >>> >>> At this stage, I can't determine whether the problem is due to AG >>> toolkit code or to one of the supporting software packages; particular >>> versions of zsi and m2crypto have caused most heartburn in the past - >>> maybe they're implicated again. I think it will take some time to find >>> and fix >>> >>> >>> chris >>> >>> >>> On 26/04/2010, at 12:51 AM, Jesus Cea Oliva wrote: >>> >>>> Hi Christoph, thanks for your indications :). >>>> >>>> I tried local VenueServers on machine, I mean, if I run Ubuntu, I >>>> execute in the same machine a local VenueServer, then, I run >>>> VenueClient and I try upload a file, the same with Windows 7. >>>> >>>> However, due to your mail, I've doing some tests: >>>> >>>> - On my Ubuntu system, I've entered at >>>> https://vv3.ap-accessgrid.org:8000/Venues/default >>>> , the Test Room, and I download files: VenueThermoClient.agpkg3 and >>>> methanol.obj, and rename it adding X (metahnol.objX). Then I can >>>> upload the files without problems to the server. >>>> >>>> I create a local network with 2 machines, with Windows 7 and Ubuntu. >>>> >>>> - Firstly I've executed a VenueServer on Ubuntu machine, then I've >>>> run VenueClients in both machines. I can't upload files in the 2 >>>> machines (well you know, really, I can upload, but the Client >>>> appears stop, reentering in the Venue I can see the files uploaded). >>>> I've noticed other problem, when I try delete a file, VenueClient >>>> don't refresh this command, the system don't delete the file. >>>> >>>> >>>> - I've executed a VenueServer on Windows 7 machine, then I've run >>>> VenueClients in both machines. Here, I can upload files without >>>> problems, on Windows and Ubuntu systems. >>>> >>>> So, now, I know that I have problems with our VenueServer on Ubuntu >>>> systems, I don't know if I have to configure something or if there >>>> are problems about names, like ñ, spaces, etc (user name, path file, >>>> etc, although my home path is /home/ercea and, there, is the >>>> VenueServer's Data folder) >>>> >>>> Now I'll try change the system language to US and I'll repeat the >>>> tests. >>>> >>>> >>>> Thanks again and apologize for any inconveniences >>>> Regards! >>>> >>>> >>>> >>>> El dia 25 abr 2010 12:11, Christoph Willing <c.will...@uq.edu.au> >>>> escribió: >>>> On 23/04/2010, at 6:24 PM, Jesus Cea Oliva wrote: >>>> >>>> Hi Crhistoph, thanks for your response :) >>>> >>>> I try upload files since 1 o 2 K (source codes, txts, etc.) and >>>> files about 3-6 Mb (PDFs, ppts...) and, all cases I have the same >>>> problem. The strange of this problem is that ocurrs randomly, and >>>> sometimes, the same file that before appears not upload, now upload >>>> (although, the majority cases, don't upload) >>>> >>>> Are you using the same venue server in each test case (Ubuntu & >>>> Windows VenueClient)? >>>> >>>> >>>> Are you using your own server or a "well known" server? >>>> If these tests are with you own server, could you instead use the >>>> APAG venue server at https://vv3.ap-accessgrid.org:8000/Venues/ >>>> default and then enter the Test venue. There are several files in >>>> that venue; could you try to download the simple.py and methanol.obj >>>> files from that venue and rename them e.g. simple.py -> simple.pyX >>>> and methanol.obj -> methanol.objX. Now upload te renamed files back >>>> into the Test venue. Do they both upload as expected? >>>> >>>> >>>> FYI, although I thought I had replicated the problem, I now find >>>> that it actually works correctly. My first hurried tests only >>>> appeared to replicate your symptoms - because I didn't wait long >>>> enogh for the upload to complete. However I have now performed the >>>> tests again and all files upload correctly - and the venue client >>>> interface shows the new data correctly in all cases. >>>> >>>> I believe the previous test appeared not to update the venue client >>>> interface because I was performing the tests from home using an ADSL >>>> connection - therefore the initial download was quite fast. The >>>> upload was slower because of the much lower upstream bandwidth of my >>>> ADSL connection. My guess is that the first portion of upload >>>> _appears_ fast because the venue client is just filling up the local >>>> TCP system buffer. However that buffer then has to be transferred >>>> (via slow ADSL uplink) to the venue server - which manifests as a >>>> fast intitial movement of the progress bar that then appears to >>>> freeze. The "freeze" is really just a pause while contents of the >>>> TCP buffer is being transferred across the network. >>>> >>>> In my case the simple.py file, being small, transfers very quickly. >>>> The methanol.obj file is about 11M - I guess this is just a bit >>>> larger than my system network buffer size - and takes a lot longer >>>> to transfer. >>>> >>>> Based on redoing the tests (using the APAG veneu server), I cannot >>>> replicate the problem. >>>> >>>> Could you confirm whether you still have the problem when you use >>>> the APAG venue server? >>>> >>>> >>>> chris >>>> >>>> >>>> On Windows I try similar files (somes files are the same), only >>>> don't work once due to a very big file. As I saw that I can upload >>>> files without problems... I try all file types. So I upload a file >>>> about 100Mb and don't work xD. >>>> >>>> Thanks for your help :). Regards! >>>> >>>> El dia 23 abr 2010 02:35, Christoph Willing <c.will...@uq.edu.au> >>>> escribió: >>>> I wonder if this is related to the size of the data object. I just >>>> tried (with Ubuntu 9.10 64bit) and found that a small file (6K) >>>> there was no problem. With a larger file (12M) I saw exactly the >>>> same problem - upload appears not to complete but has actually >>>> succeeded. Re-entering the venue shows that the new data is there. >>>> >>>> In your Windows test, are you uploading the same file (or something >>>> of similar size) as in the Ubuntu case? >>>> >>>> I will now be away for a few days so I can't do any more >>>> investigation myself until I return. Maybe others can look into this >>>> in the meantime ... >>>> >>>> >>>> chris >>>> >>>> >>>> On 23/04/2010, at 5:28 AM, Jesus Cea Oliva wrote: >>>> >>>> Thanks Tom for your fast response :). >>>> >>>> Well, atach the VenueClient log in this email, I can't see any >>>> problems :S. >>>> >>>> >>>> >>>> 04/22/10 21:16:50 140471913264880 VenueClient VenueClientUI.py: 1308 >>>> DEBUG VenueClientUI.AddDataCB: Trying to upload to >>>> 'ftps://localhost:8006/7f00010133381d7afaba1c148fcabe1007' >>>> 04/22/10 21:17:00 140471913264880 VenueClient VenueClientUI.py: 1395 >>>> DEBUG AddDataCB: URI of parent is >>>> 04/22/10 21:17:00 140471913264880 VenueClientController >>>> VenueClientController.py:821 DEBUG In >>>> VenueClientController.UploadVenueFiles >>>> 04/22/10 21:17:00 140471913264880 VenueClientController >>>> VenueClientController.py:822 DEBUG fileList = [u'/home/ercea/ >>>> Descargas/DSD-Tutorial-3.pdf'] >>>> 04/22/10 21:17:00 140471913264880 VenueClientController >>>> VenueClientController.py:848 DEBUG Serverpath: >>>> 04/22/10 21:17:00 140471913264880 VenueClientController >>>> VenueClientController.py:857 DEBUG Have args, creating thread, url: >>>> ftps://localhost:8006/7f00010133381d7afaba1c148fcabe1007 >>>> , files: [u'/home/ercea/Descargas/DSD-Tutorial-3.pdf'] >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:944 DEBUG Upload: getting identity >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:949 DEBUG Got identity <?xml >>>> version="1.0" ? ><Subject><Subject auth_data="" auth_type="x509" >>>> name="/O=Access Grid/O=Argonne National Laboratory/OU=Futures Lab >>>> Anonymous Authority/CN=Anonymous User >>>> 17130398eef9fd82cba8dc9d845a1768"/></ Subject> >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:950 DEBUG get_ident_and_upload: Upload URL >>>> ftps://localhost:8006/7f00010133381d7afaba1c148fcabe1007 >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:953 DEBUG Get_ident_and_upload: Word is: >>>> ftps: >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:953 DEBUG Get_ident_and_upload: Word is: >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:953 DEBUG Get_ident_and_upload: Word is: >>>> localhost:8006 >>>> 04/22/10 21:17:00 140471479552272 VenueClientController >>>> VenueClientController.py:953 DEBUG Get_ident_and_upload: Word is: >>>> 7f00010133381d7afaba1c148fcabe1007 >>>> 04/22/10 21:17:00 140471479552272 DataStore DataStore.py:1267 INFO >>>> UploadFiles: ftps://localhost: >>>> 8006/7f00010133381d7afaba1c148fcabe1007 [u'/home/ercea/Descargas/ >>>> DSD- Tutorial-3.pdf'] >>>> 04/22/10 21:17:00 140471479552272 DataStore DataStore.py:1271 DEBUG >>>> UploadFiles: ftps://localhost: >>>> 8006/7f00010133381d7afaba1c148fcabe1007 /home/ercea/Descargas/DSD- >>>> Tutorial-3.pdf [u'/home/ercea/Descargas/ DSD-Tutorial-3.pdf'] >>>> 04/22/10 21:17:00 140471479552272 FTPSClient FTPSClient.py:141 DEBUG >>>> Entered FTPSUploadFile: localfile=/home/ercea/Descargas/DSD- >>>> Tutorial-3.pdf url=ftps://localhost:8006/7f00010133381d7afaba1c148fcabe1007 >>>> 04/22/10 21:17:00 140471913264880 VenueClientController >>>> VenueClientController.py:862 DEBUG Started thread >>>> 04/22/10 21:17:20 140471517653264 VenueClient VenueClient.py:605 >>>> DEBUG Calling Heartbeat, time now: 1271963840 >>>> 04/22/10 21:17:20 140471517653264 VenueClient VenueClient.py:629 >>>> DEBUG Next Heartbeat needed within 36s >>>> 04/22/10 21:17:20 140471517653264 VenueClient VenueClient.py:639 >>>> DEBUG heartBeatCounter = 2 >>>> 04/22/10 21:17:56 140471401707792 VenueClient VenueClient.py:605 >>>> DEBUG Calling Heartbeat, time now: 1271963876 >>>> 04/22/10 21:17:56 140471401707792 VenueClient VenueClient.py:629 >>>> DEBUG Next Heartbeat needed within 36s >>>> 04/22/10 21:17:56 140471401707792 VenueClient VenueClient.py:639 >>>> DEBUG heartBeatCounter = 3 >>>> >>>> >>>> Here increment heartBeatCounter +1. >>>> >>>> Ah, I have this problem, in the same network, on ubuntu system >>>> (Ubuntu 9.10 64 bits), but I've tested on Windows 7 and I can upload >>>> files without problems. >>>> >>>> Regards! >>>> >>>> >>>> El dia 22 abr 2010 18:29, Thomas Uram <tu...@mcs.anl.gov> escribió: >>>> Hi Jesus: >>>> >>>> There is probably some indication of the problem in the >>>> VenueClient.log file. If you submit a bug report from the Help menu >>>> after this happens, a recent portion of the VenueClient.log file >>>> will be included with the report, and we can review it. If you want >>>> to look yourself, the log file resides in the following locations >>>> based on platform: >>>> MacOS, Linux: ~/.AccessGrid3/Logs >>>> Windows: c:\documents and settings\username\application data >>>> \AccessGrid3\Logs (adjusted for your locale, of course) >>>> Tom >>>> >>>> On Apr 22, 2010, at 11:11 AM, Jesus Cea Oliva wrote: >>>> >>>> Hi list again, sorry for my constants problems xD. >>>> >>>> I'm trying upload files to our VenueServer and my problem is >>>> strange. Firstly, I've created a VenueServer locally, for testing. >>>> >>>> Well, when I try to upload a file, I can see the message on the >>>> VenueClient "Uploading file X" and a progressbar, but, when the >>>> progressbar is near to the end, stops and list of files (Data >>>> section) don't refresh. So I beleived that the file can't upload. >>>> >>>> But, really, the file has uploaded, because I can see the folder >>>> Data of my local VenueServer, and the file is copied in this folder. >>>> In this case, if I reset VenueServer and VenueClient, then, I can >>>> see the files in Data section. >>>> >>>> So I think that the file uploads fine but, the list of files don't >>>> refresh and the file uploaded don't appears. >>>> >>>> I must say that, sometimes, I can upload a file so I don't know >>>> whats really the problem (ports closed? firewall? path or name >>>> files? file size?) . >>>> >>>> Thanks again and apologize for any inconveniences >>>> >>>> Regards! >>>> >>>> >>>> >>>> >>>> >>>> >>>> >>>> >>>> >>>> <VenueClient.log> >>>> >>>> Christoph Willing +61 7 3365 8316 >>>> QCIF Access Grid Manager >>>> University of Queensland >>>> >>>> Christoph Willing +61 7 3365 8316 >>>> QCIF Access Grid Manager >>>> University of Queensland >>>> >>>> >>> >>> Christoph Willing +61 7 3365 8316 >>> QCIF Access Grid Manager >>> University of Queensland >>> >> > > Christoph Willing +61 7 3365 8316 > QCIF Access Grid Manager > University of Queensland >
02/03/10 09:30:49 5956 VenueServer venueserver.py:841 INFO Checkpoint starting at: Wed Feb 03 09:30:49 2010 02/03/10 09:30:49 5956 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:30:50 5956 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:30:50 5956 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:30:50 5956 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:30:50 5956 VenueServer venueserver.py:875 INFO Checkpointing venues completed at: Wed Feb 03 09:30:50 2010 02/03/10 09:31:12 6012 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:12 6012 VenueServer venue.py:481 INFO authorizing subject None for action urn:#Enter 02/03/10 09:31:12 6012 Security authorizationmanager.py:270 DEBUG Authorizing action Enter for None 02/03/10 09:31:12 6012 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:12 6012 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:31:12 6012 VenueServer venue.py:488 INFO User None authorized for action Enter 02/03/10 09:31:13 6012 VenueServer venue.py:1177 DEBUG Enter called. 02/03/10 09:31:13 6012 VenueServer venue.py:1180 DEBUG Enter: Assigning connection id: c0ec25bc121c753ee50ec478d878a455 02/03/10 09:31:13 6012 Usage venue.py:1193 INFO "Enter","","USD Venue Server Lobby","c0ec25bc12c81692ab49ac85b98264eb" 02/03/10 09:31:13 6012 VenueServer venue.py:1194 DEBUG Current users: 02/03/10 09:31:13 6012 VenueServer venue.py:1196 DEBUG VenueClientState(name=USD Med Laptop connectionId=c0ec25bc121c753ee50ec478d878a455) 02/03/10 09:31:13 6012 VenueServer venue.py:1197 DEBUG Enter: Distribute enter event 02/03/10 09:31:13 6012 VenueServer venue.py:659 DEBUG List of DataDescriptions 02/03/10 09:31:13 6012 VenueServer venue.py:1208 DEBUG state: <AccessGrid.Descriptions.VenueState instance at 0x04C4C940> 02/03/10 09:31:13 1264 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:13 1264 VenueServer venue.py:481 INFO authorizing subject None for action urn:#GetState 02/03/10 09:31:13 1264 Security authorizationmanager.py:270 DEBUG Authorizing action GetState for None 02/03/10 09:31:13 1264 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:13 1264 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:31:13 1264 VenueServer venue.py:488 INFO User None authorized for action GetState 02/03/10 09:31:14 1264 VenueServer venue.py:659 DEBUG List of DataDescriptions 02/03/10 09:31:14 1264 FTPSServer ftpsserver.py:92 DEBUG Entered FTPSServer.GetUploadDescriptor 02/03/10 09:31:14 1264 FTPSServer ftpsserver.py:97 DEBUG descriptor = ftps://usd-venue.usd.edu:8006/c0ec25bc12c81692ab49ac85b98264eb 02/03/10 09:31:14 6020 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:14 6020 VenueServer venue.py:481 INFO authorizing subject None for action urn:#NegotiateCapabilities 02/03/10 09:31:14 6020 Security authorizationmanager.py:270 DEBUG Authorizing action NegotiateCapabilities for None 02/03/10 09:31:14 6020 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:14 6020 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:31:14 6020 VenueServer venue.py:488 INFO User None authorized for action NegotiateCapabilities 02/03/10 09:31:14 6020 VenueServer venue.py:839 DEBUG negotiate capabilities 02/03/10 09:31:14 6020 VenueServer venue.py:848 DEBUG Capability to be processed: producer, video, ceb001630ec0753eccd2857f73d22d27, H261, 90000, 1, None, None 02/03/10 09:31:14 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, video, ceb001630ec0753ecbfaa986882883a3, H261, 90000, 1, None, None 02/03/10 09:31:14 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, L16, 16000, 1, None, None 02/03/10 09:31:14 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, L16, 8000, 1, None, None 02/03/10 09:31:14 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, L8, 16000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, L8, 8000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, PCMU, 16000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, PCMU, 8000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, GSM, 16000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, audio, ceb001630ec0753ed0cdbb6177e164da, GSM, 8000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: producer, audio, ceb001630ec0753ed0cdbb6177e164da, L16, 16000, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: producer, Beacon, 1, ANY, 0, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:848 DEBUG Capability to be processed: consumer, Beacon, 1, ANY, 0, 1, None, None 02/03/10 09:31:15 6020 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.228.101 224.2.218.127 02/03/10 09:31:15 6020 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 54650 52620 02/03/10 09:31:15 6020 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.228.101 224.2.228.101 02/03/10 09:31:15 6020 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 54650 54650 02/03/10 09:31:15 6020 VenueServer venue.py:874 DEBUG added user as producer of existent stream 02/03/10 09:31:15 6020 NetworkServicesManager networkservicesmanager.py:88 DEBUG ResolveMismatch: Match streams to capabilities 02/03/10 09:31:15 6020 NetworkServicesManager networkservicesmanager.py:109 DEBUG ResolveMismatch: There are no network services available, ignore matching 02/03/10 09:31:15 6020 VenueServer venue.py:893 DEBUG Stream doesn't exist create new one! 02/03/10 09:31:15 6020 VenueServer venue.py:898 DEBUG Stream type: Beacon! 02/03/10 09:31:16 6020 VenueServer venue.py:909 DEBUG Caps: producer, Beacon, 1, ANY, 0, 1, None, None 02/03/10 09:31:16 6020 VenueServer venue.py:909 DEBUG Caps: consumer, Beacon, 1, ANY, 0, 1, None, None 02/03/10 09:31:16 6020 VenueServer venue.py:915 DEBUG Stream prefers multicast! 02/03/10 09:31:16 6020 VenueServer venue.py:918 DEBUG Stream address: 224.2.246.59 : 51028 02/03/10 09:31:16 6020 VenueServer venue.py:928 DEBUG added user as producer of non-existent stream 02/03/10 09:31:16 6020 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.246.59 224.2.218.127 02/03/10 09:31:16 6020 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 51028 52620 02/03/10 09:31:16 6020 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.246.59 224.2.228.101 02/03/10 09:31:16 6020 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 51028 54650 02/03/10 09:31:16 6020 VenueServer venue.py:2409 DEBUG * * * Added stream producer c0ec25bc121c753ee50ec478d878a455 02/03/10 09:31:16 6020 VenueServer venue.py:933 DEBUG sending AddStream event; stream=USD Venue Server Lobby 02/03/10 09:31:16 6020 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.218.127 224.2.218.127 02/03/10 09:31:16 6020 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 52620 52620 02/03/10 09:31:16 6020 VenueServer venue.py:874 DEBUG added user as producer of existent stream 02/03/10 09:31:16 6020 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.218.127 224.2.218.127 02/03/10 09:31:17 6020 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 52620 52620 02/03/10 09:31:17 6020 VenueServer venue.py:874 DEBUG added user as producer of existent stream 02/03/10 09:31:18 4136 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:18 4556 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:18 4136 VenueServer venue.py:481 INFO authorizing subject None for action urn:#GetConnections 02/03/10 09:31:18 4136 Security authorizationmanager.py:270 DEBUG Authorizing action GetConnections for None 02/03/10 09:31:18 4556 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 02/03/10 09:31:18 4136 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:18 4556 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 02/03/10 09:31:18 4136 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:31:19 4556 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:19 4136 VenueServer venue.py:488 INFO User None authorized for action GetConnections 02/03/10 09:31:19 4556 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:31:19 4556 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 02/03/10 09:31:19 4556 VenueServer venue.py:963 DEBUG UpdateLifetime: c0ec25bc121c753ee50ec478d878a455 USD Med Laptop ; next heartbeat by 36 02/03/10 09:31:19 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211199 02/03/10 09:31:19 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211199 vs 1265211079 02/03/10 09:31:34 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211199 02/03/10 09:31:34 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211199 vs 1265211094 02/03/10 09:31:49 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211199 02/03/10 09:31:49 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211199 vs 1265211109 02/03/10 09:31:55 4368 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:55 4368 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 02/03/10 09:31:55 4368 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 02/03/10 09:31:55 4368 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:31:55 4368 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:31:55 4368 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 02/03/10 09:31:56 4368 VenueServer venue.py:963 DEBUG UpdateLifetime: c0ec25bc121c753ee50ec478d878a455 USD Med Laptop ; next heartbeat by 36 02/03/10 09:32:04 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211236 02/03/10 09:32:04 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211236 vs 1265211124 02/03/10 09:32:07 4180 FTPSServer ftps_server.py:338 INFO Connection from 206.176.1.99:1114 02/03/10 09:32:07 4180 FTPSServer ftps_server.py:271 INFO ==> 220 usd-venue M2Crypto (Medusa) FTP/TLS server v0.17 ready. 02/03/10 09:32:07 4180 FTPSServer ftps_server.py:271 INFO <== AUTH TLS 02/03/10 09:32:07 4180 FTPSServer ftps_server.py:271 INFO ==> 234 AUTH TLS successful 02/03/10 09:32:07 4180 FTPSServer ftps_server.py:271 INFO <== PBSZ 0 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 200 PBSZ=0 successful. 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO <== PROT P 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 200 Protection set to Private 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO <== USER c0ec25bc12c81692ab49ac85b98264eb 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 331 Password required. 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO <== PASS <password> 02/03/10 09:32:08 4180 VenueServer venueserver.py:433 INFO in authorizeDataTransferCB <AccessGrid.FTPS.ftps_server.ftp_tls_channel connected 206.176.1.99:1114 at 0x30c5a08> c0ec25bc12c81692ab49ac85b98264eb c0ec25bc121c753ee50ec478d878a455 02/03/10 09:32:08 4180 VenueServer venueserver.py:459 INFO authorizeDataTransferCB: user in venue, authorized USD Med Laptop to transfer files to venue USD Venue Server Lobby 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 230 Ok. 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:275 INFO Successful login: Filesystem=<unix-style fs root:Data wd:/> 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO <== cwd /c0ec25bc12c81692ab49ac85b98264eb 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 250 CWD command successful. 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO <== TYPE I 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 200 Type set to Binary. 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO <== PASV 02/03/10 09:32:08 4180 FTPSServer ftps_server.py:271 INFO ==> 227 Entering Passive Mode (192,236,37,188,195,86) 02/03/10 09:32:19 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211236 02/03/10 09:32:19 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211236 vs 1265211139 02/03/10 09:32:32 1792 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:32:32 1792 VenueServer venue.py:481 INFO authorizing subject None for action urn:#UpdateLifetime 02/03/10 09:32:32 1792 Security authorizationmanager.py:270 DEBUG Authorizing action UpdateLifetime for None 02/03/10 09:32:32 1792 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:32:32 1792 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:32:33 1792 VenueServer venue.py:488 INFO User None authorized for action UpdateLifetime 02/03/10 09:32:33 1792 VenueServer venue.py:963 DEBUG UpdateLifetime: c0ec25bc121c753ee50ec478d878a455 USD Med Laptop ; next heartbeat by 36 02/03/10 09:32:34 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211273 02/03/10 09:32:34 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211273 vs 1265211154 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:338 INFO Connection from 206.176.1.99:1119 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO ==> 220 usd-venue M2Crypto (Medusa) FTP/TLS server v0.17 ready. 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO <== AUTH TLS 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO ==> 234 AUTH TLS successful 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO <== PBSZ 0 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO ==> 200 PBSZ=0 successful. 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO <== PROT P 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO ==> 200 Protection set to Private 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO <== USER c0ec25bc12c81692ab49ac85b98264eb 02/03/10 09:32:47 4180 FTPSServer ftps_server.py:271 INFO ==> 331 Password required. 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:271 INFO <== PASS <password> 02/03/10 09:32:48 4180 VenueServer venueserver.py:433 INFO in authorizeDataTransferCB <AccessGrid.FTPS.ftps_server.ftp_tls_channel connected 206.176.1.99:1119 at 0x2cc23c8> c0ec25bc12c81692ab49ac85b98264eb c0ec25bc121c753ee50ec478d878a455 02/03/10 09:32:48 4180 VenueServer venueserver.py:459 INFO authorizeDataTransferCB: user in venue, authorized USD Med Laptop to transfer files to venue USD Venue Server Lobby 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:271 INFO ==> 230 Ok. 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:275 INFO Successful login: Filesystem=<unix-style fs root:Data wd:/> 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:271 INFO <== cwd /c0ec25bc12c81692ab49ac85b98264eb 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:271 INFO ==> 250 CWD command successful. 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:271 INFO <== TYPE I 02/03/10 09:32:48 4180 FTPSServer ftps_server.py:271 INFO ==> 200 Type set to Binary. 02/03/10 09:32:49 4180 FTPSServer ftps_server.py:271 INFO <== PASV 02/03/10 09:32:49 4180 FTPSServer ftps_server.py:271 INFO ==> 227 Entering Passive Mode (192,236,37,188,195,88) 02/03/10 09:32:49 5292 VenueServer venue.py:741 DEBUG CleanupClients: client c0ec25bc121c753ee50ec478d878a455 USD Med Laptop timeout=1265211273 02/03/10 09:32:50 5292 VenueServer venue.py:244 DEBUG Client Timeout Check: 1265211273 vs 1265211169 02/03/10 09:33:01 4724 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:33:01 4724 VenueServer venue.py:481 INFO authorizing subject None for action urn:#Exit 02/03/10 09:33:01 4724 Security authorizationmanager.py:270 DEBUG Authorizing action Exit for None 02/03/10 09:33:01 4724 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:33:01 4724 Security authorizationmanager.py:282 DEBUG Accepting access from unidentified user as part of Everybody role 02/03/10 09:33:01 4724 VenueServer venue.py:488 INFO User None authorized for action Exit 02/03/10 09:33:01 4724 VenueServer venue.py:1676 DEBUG Called Venue Exit on c0ec25bc121c753ee50ec478d878a455 02/03/10 09:33:01 4724 Usage venue.py:1685 INFO "Exit","","USD Venue Server Lobby","c0ec25bc12c81692ab49ac85b98264eb" 02/03/10 09:33:01 4724 VenueServer venue.py:1062 DEBUG Called RemoveUser on c0ec25bc121c753ee50ec478d878a455 02/03/10 09:33:02 4724 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.218.127 224.2.218.127 02/03/10 09:33:02 4724 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 52620 52620 02/03/10 09:33:02 4724 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.228.101 224.2.218.127 02/03/10 09:33:02 4724 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 54650 52620 02/03/10 09:33:02 4724 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.228.101 224.2.228.101 02/03/10 09:33:02 4724 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 54650 54650 02/03/10 09:33:02 4724 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.246.59 224.2.218.127 02/03/10 09:33:02 4724 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 51028 52620 02/03/10 09:33:02 4724 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.246.59 224.2.228.101 02/03/10 09:33:02 4724 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 51028 54650 02/03/10 09:33:02 4724 VenueServer venue.py:2520 DEBUG StreamDescriptionList.index Address 224.2.246.59 224.2.246.59 02/03/10 09:33:02 4724 VenueServer venue.py:2523 DEBUG StreamDescriptionList.index Port 51028 51028 02/03/10 09:33:03 4724 Usage venue.py:1082 INFO "RemoveUser","","USD Venue Server Lobby","c0ec25bc12c81692ab49ac85b98264eb" 02/03/10 09:33:03 4724 VenueServer venue.py:1106 DEBUG Removing single instance of user 02/03/10 09:33:03 4724 VenueServer venue.py:1128 DEBUG RemoveUser: Distribute EXIT event 02/03/10 09:33:04 4180 FTPSServer ftps_server.py:275 INFO recv: closing channel <AccessGrid.FTPS.ftps_server.ftp_tls_channel connected 206.176.1.99:1119 at 0x2cc23c8> (0, 'Error') 02/03/10 09:33:12 5176 Security authorizationmanager.py:270 DEBUG Authorizing action Checkpoint for <?xml version="1.0" ?> <Subject><Subject auth_data="" auth_type="x509" name="/O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/usd-venue.usd.edu"/></Subject> 02/03/10 09:33:13 5176 Security authorizationmanager.py:295 DEBUG Accepting access from /O=Access Grid/OU=agdev-ca.mcs.anl.gov/CN=VenueServer/usd-venue.usd.edu 02/03/10 09:33:13 5176 VenueServer venueserver.py:841 INFO Checkpoint starting at: Wed Feb 03 09:33:13 2010 02/03/10 09:33:13 5176 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:33:13 5176 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:33:13 5176 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:33:13 5176 Security authorizationmanager.py:307 DEBUG IsIdentificationRequired returning <type 'int'> 0 02/03/10 09:33:13 5176 VenueServer venueserver.py:875 INFO Checkpointing venues completed at: Wed Feb 03 09:33:13 2010