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

Reply via email to