[2019-09-07 19:01:18.707485 +0200][Debug ][Utility ] Unable to process user config file: [ERROR] OS Error: No such file or directory [2019-09-07 19:01:18.707608 +0200][Debug ][PlugInMgr ] Initializing plug-in manager... [2019-09-07 19:01:18.707621 +0200][Debug ][PlugInMgr ] No default plug-in, loading plug-in configs... [2019-09-07 19:01:18.707629 +0200][Debug ][PlugInMgr ] Processing plug-in definitions in /etc/xrootd/client.plugins.d... [2019-09-07 19:01:18.707707 +0200][Debug ][PlugInMgr ] Processing plug-in definitions in /var/lib/condor/.xrootd/client.plugins.d... [2019-09-07 19:01:18.707723 +0200][Debug ][PlugInMgr ] Unable to process directory /var/lib/condor/.xrootd/client.plugins.d: [ERROR] OS Error: No such file or directory [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] URL: file://///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.724991 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.725399 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.725499 +0200][Dump ][Utility ] Adding job with properties: 'checkSumMode' = 'none', 'chunkSize' = '16777216', 'coerce' = '0', 'dynamicSource' = '0', 'force' = '0', 'initTimeout' = '600', 'makeDir' = '0', 'parallelChunks' = '4', 'posc' = '1', 'source' = 'file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix', 'target' = 'root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix', 'thirdParty' = 'first', 'tpcTimeout' = '60', 'xcp' = '0', 'xcpBlockSize' = '134217728', 'zipArchive' = '0' [2019-09-07 19:01:18.725645 +0200][Debug ][Utility ] CopyProcess: 2 jobs to prepare [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.725676 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.725706 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.725743 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.725770 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.725782 +0200][Debug ][Utility ] Creating a third party fall back copy job, from file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix to root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.725816 +0200][Debug ][JobMgr ] Starting the job manager... [2019-09-07 19:01:18.725855 +0200][Debug ][JobMgr ] Job manager started, 1 workers [2019-09-07 19:01:18.725967 +0200][Debug ][Utility ] Monitor library name not set. No monitoring [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.727104 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.727163 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.727225 +0200][Debug ][Poller ] Available pollers: built-in [2019-09-07 19:01:18.727238 +0200][Debug ][Poller ] Attempting to create a poller according to preference: built-in [2019-09-07 19:01:18.727249 +0200][Debug ][Poller ] Creating poller: built-in [2019-09-07 19:01:18.727277 +0200][Debug ][Poller ] Creating and starting the built-in poller... [2019-09-07 19:01:18.727443 +0200][Debug ][Poller ] Using 1 poller threads [2019-09-07 19:01:18.727461 +0200][Debug ][TaskMgr ] Starting the task manager... [2019-09-07 19:01:18.727511 +0200][Debug ][TaskMgr ] Task manager started [2019-09-07 19:01:18.727523 +0200][Debug ][JobMgr ] Starting the job manager... [2019-09-07 19:01:18.727627 +0200][Debug ][JobMgr ] Job manager started, 3 workers [2019-09-07 19:01:18.727642 +0200][Debug ][TaskMgr ] Registering task: "FileTimer task" to be run at: [2019-09-07 19:01:18 +0200] [2019-09-07 19:01:18.727657 +0200][Debug ][Utility ] Creating a third party copy job, from file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix to root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727681 +0200][Debug ][Utility ] TPC is not supported, falling back to streaming mode. [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.727744 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.727789 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.727813 +0200][Debug ][Utility ] Creating a classic copy job, from file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix to root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727887 +0200][Debug ][Utility ] Opening file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix for reading [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.727926 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.727967 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728018 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.728039 +0200][Debug ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Sending an open command [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728116 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] URL: file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] Protocol: file [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] Host Name: localhost [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728185 +0200][Dump ][Utility ] Path: ///tmp/1M [2019-09-07 19:01:18.728424 +0200][Debug ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Open has returned with status [SUCCESS] [2019-09-07 19:01:18.728463 +0200][Debug ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] successfully opened at localhost, handle: 0xf, session id: 1 [2019-09-07 19:01:18.728551 +0200][Debug ][Utility ] Opening root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix for writing [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728594 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728646 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728702 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:18.728722 +0200][Debug ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Sending an open command [2019-09-07 19:01:18.728751 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Sending message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] URL: golias100.farm.particle.cz:1094 [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:18.728794 +0200][Dump ][Utility ] Path: [2019-09-07 19:01:18.728823 +0200][Debug ][PostMaster ] Creating new channel to: golias100.farm.particle.cz:1094 1 stream(s) [2019-09-07 19:01:18.728862 +0200][Debug ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800 [2019-09-07 19:01:18.728969 +0200][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: golias100.farm.particle.cz:1094" to be run at: [2019-09-07 19:01:33 +0200] [2019-09-07 19:01:18.728993 +0200][Debug ][ExDbgMsg ] [golias100.farm.particle.cz:1094] MsgHandler created: 0x8c0080f0 (message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) ). [2019-09-07 19:01:18.729014 +0200][Dump ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Sending message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) (0x8c0071c0) through substream 0 expecting answer at 0 [2019-09-07 19:01:18.731301 +0200][Debug ][PostMaster ] [golias100.farm.particle.cz:1094] Found 2 address(es): [::ffff:147.231.25.100]:1094, [2001:718:401:6017:2::1000]:1094 [2019-09-07 19:01:18.731372 +0200][Debug ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Attempting connection to [2001:718:401:6017:2::1000]:1094 [2019-09-07 19:01:18.731447 +0200][Debug ][Poller ] Adding socket 0x8c007c20 to the poller [2019-09-07 19:01:18.731475 +0200][Dump ][Poller ] <--> Enable write notifications, timeout: 15 [2019-09-07 19:01:18.732196 +0200][Dump ][Poller ] <--> Got an event: ReadyToWrite [2019-09-07 19:01:18.732237 +0200][Debug ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Async connection call returned [2019-09-07 19:01:18.732271 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Sending out the initial hand shake + kXR_protocol [2019-09-07 19:01:18.732292 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Enable read notifications, timeout: 15 [2019-09-07 19:01:18.732313 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToWrite [2019-09-07 19:01:18.732350 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Wrote a message: (0x7c000950), 44 bytes [2019-09-07 19:01:18.732364 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Disable write notifications [2019-09-07 19:01:18.733078 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:18.733126 +0200][Dump ][XRootDTransport ] [msg: 0x7c000a80] Expecting 8 bytes of message body [2019-09-07 19:01:18.733139 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header, size: 8 [2019-09-07 19:01:18.733154 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received a message of 16 bytes [2019-09-07 19:01:18.733176 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Got the server hand shake response (type: manager [], protocol version 400) [2019-09-07 19:01:18.733195 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:18.733209 +0200][Dump ][XRootDTransport ] [msg: 0x7c000a80] Expecting 8 bytes of message body [2019-09-07 19:01:18.733220 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header, size: 8 [2019-09-07 19:01:18.733233 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received a message of 16 bytes [2019-09-07 19:01:18.733246 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] kXR_protocol successful (type: manager [], protocol version 400) [2019-09-07 19:01:18.733484 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Sending out kXR_login request, username: condor, cgi: ?xrd.cc=cz&xrd.tz=1&xrd.appname=python2.7&xrd.info=&xrd.hostname=mff82.farm.particle.cz&xrd.rn=v4.9.1, dual-stack: true, private IPv4: true, private IPv6: false [2019-09-07 19:01:18.733507 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Enable write notifications, timeout: 15 [2019-09-07 19:01:18.733528 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToWrite [2019-09-07 19:01:18.733564 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Wrote a message: (0x7c000ba0), 125 bytes [2019-09-07 19:01:18.733577 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Disable write notifications [2019-09-07 19:01:18.734406 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:18.734451 +0200][Dump ][XRootDTransport ] [msg: 0x7c0009a0] Expecting 68 bytes of message body [2019-09-07 19:01:18.734477 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header, size: 8 [2019-09-07 19:01:18.734493 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received a message of 76 bytes [2019-09-07 19:01:18.734512 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Logged in, session: f9f50000f435000072080000d9680f00 [2019-09-07 19:01:18.734524 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Authentication is required: &P=gsi,v:10400,c:ssl,ca:e523eeaa.0|1db3b27f.0&P=unix [2019-09-07 19:01:18.734535 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Sending authentication data [2019-09-07 19:01:18.736021 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Trying to authenticate using gsi [2019-09-07 19:01:18.758850 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Enable write notifications, timeout: 15 [2019-09-07 19:01:18.758894 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToWrite [2019-09-07 19:01:18.758934 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Wrote a message: (0x7c010cc0), 136 bytes [2019-09-07 19:01:18.758948 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Disable write notifications [2019-09-07 19:01:18.995960 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:18.996008 +0200][Dump ][XRootDTransport ] [msg: 0x7c0009a0] Expecting 3840 bytes of message body [2019-09-07 19:01:18.996022 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header, size: 8 [2019-09-07 19:01:18.996039 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received a message of 3848 bytes [2019-09-07 19:01:18.996052 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Sending more authentication data for gsi [2019-09-07 19:01:19.002662 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.002696 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToWrite [2019-09-07 19:01:19.002740 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Wrote a message: (0x7c023470), 9838 bytes [2019-09-07 19:01:19.002752 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Disable write notifications [2019-09-07 19:01:19.022594 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:19.022642 +0200][Dump ][XRootDTransport ] [msg: 0x7c01f0c0] Expecting 0 bytes of message body [2019-09-07 19:01:19.022656 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header, size: 8 [2019-09-07 19:01:19.022668 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received a message of 8 bytes [2019-09-07 19:01:19.022734 +0200][Debug ][XRootDTransport ] [golias100.farm.particle.cz:1094 #0.0] Authenticated with gsi. [2019-09-07 19:01:19.022750 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.022766 +0200][Debug ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Stream 0 connected. [2019-09-07 19:01:19.022785 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToWrite [2019-09-07 19:01:19.022833 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Wrote a message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) (0x8c0071c0), 168 bytes [2019-09-07 19:01:19.022859 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Successfully sent message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) (0x8c0071c0). [2019-09-07 19:01:19.022882 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) has been successfully sent. [2019-09-07 19:01:19.022906 +0200][Debug ][ExDbgMsg ] [golias100.farm.particle.cz:1094] Moving MsgHandler: 0x8c0080f0 (message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) ) from out-queu to in-queue. [2019-09-07 19:01:19.022918 +0200][Dump ][PostMaster ] [golias100.farm.particle.cz:1094 #0.0] All messages consumed, disable uplink [2019-09-07 19:01:19.022928 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Disable write notifications [2019-09-07 19:01:19.031423 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:19.031465 +0200][Dump ][XRootDTransport ] [msg: 0x7c010090] Expecting 385 bytes of message body [2019-09-07 19:01:19.031478 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header for 0x7c010090 size: 8 [2019-09-07 19:01:19.031500 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message 0x7c010090 of 393 bytes [2019-09-07 19:01:19.031515 +0200][Dump ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Handling received message: 0x7c010090. [2019-09-07 19:01:19.031593 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Got kXR_redirect response to message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ): dpmpool19.farm.particle.cz?&dpm.time=1567875679&dpm.dhost=dpmpool19.farm.particle.cz&dpm.put=1&dpm.loc=1&dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.nonce=25f07727&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==, port 1095 [2019-09-07 19:01:19.031621 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Current server has been assigned as a load-balancer for message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] URL: dpmpool19.farm.particle.cz:1095/ [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] Host Name: dpmpool19.farm.particle.cz [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] Port: 1095 [2019-09-07 19:01:19.031652 +0200][Dump ][Utility ] Path: [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] URL: fake://fake:111//fake?&dpm.time=1567875679&dpm.dhost=dpmpool19.farm.particle.cz&dpm.put=1&dpm.loc=1&dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.nonce=25f07727&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix& [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] Protocol: fake [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] Host Name: fake [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] Port: 111 [2019-09-07 19:01:19.031730 +0200][Dump ][Utility ] Path: /fake [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] URL: root://dpmpool19.farm.particle.cz:1095/?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix& [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] Host Name: dpmpool19.farm.particle.cz [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] Port: 1095 [2019-09-07 19:01:19.031800 +0200][Dump ][Utility ] Path: [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] URL: fake://fake:111///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] Protocol: fake [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] Host Name: fake [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] Port: 111 [2019-09-07 19:01:19.031847 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] URL: dpmpool19.farm.particle.cz:1095 [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] Host Name: dpmpool19.farm.particle.cz [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] Port: 1095 [2019-09-07 19:01:19.031933 +0200][Dump ][Utility ] Path: [2019-09-07 19:01:19.031960 +0200][Debug ][PostMaster ] Creating new channel to: dpmpool19.farm.particle.cz:1095 1 stream(s) [2019-09-07 19:01:19.031994 +0200][Debug ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Stream parameters: Network Stack: IPAuto, Connection Window: 120, ConnectionRetry: 5, Stream Error Window: 1800 [2019-09-07 19:01:19.032097 +0200][Debug ][TaskMgr ] Registering task: "TickGeneratorTask for: dpmpool19.farm.particle.cz:1095" to be run at: [2019-09-07 19:01:34 +0200] [2019-09-07 19:01:19.032123 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Retry at server MsgHandler: 0x8c0080f0 (message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) ). [2019-09-07 19:01:19.032149 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Sending message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) (0x8c0071c0) through substream 0 expecting answer at 0 [2019-09-07 19:01:19.034264 +0200][Debug ][PostMaster ] [dpmpool19.farm.particle.cz:1095] Found 2 address(es): [::ffff:147.231.25.64]:1095, [2001:718:401:6017:2::19]:1095 [2019-09-07 19:01:19.034319 +0200][Debug ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Attempting connection to [2001:718:401:6017:2::19]:1095 [2019-09-07 19:01:19.034373 +0200][Debug ][Poller ] Adding socket 0x800032d0 to the poller [2019-09-07 19:01:19.034406 +0200][Dump ][Poller ] <--> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.034992 +0200][Dump ][Poller ] <--> Got an event: ReadyToWrite [2019-09-07 19:01:19.035033 +0200][Debug ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Async connection call returned [2019-09-07 19:01:19.035060 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Sending out the initial hand shake + kXR_protocol [2019-09-07 19:01:19.035082 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable read notifications, timeout: 15 [2019-09-07 19:01:19.035103 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:19.035136 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Wrote a message: (0x7c0009a0), 44 bytes [2019-09-07 19:01:19.035149 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:19.035746 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:19.035770 +0200][Dump ][XRootDTransport ] [msg: 0x7c0009a0] Expecting 8 bytes of message body [2019-09-07 19:01:19.035781 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header, size: 8 [2019-09-07 19:01:19.035795 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received a message of 16 bytes [2019-09-07 19:01:19.035809 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Got the server hand shake response (type: server [], protocol version 400) [2019-09-07 19:01:19.035825 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:19.035839 +0200][Dump ][XRootDTransport ] [msg: 0x7c0009a0] Expecting 8 bytes of message body [2019-09-07 19:01:19.035849 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header, size: 8 [2019-09-07 19:01:19.035873 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received a message of 16 bytes [2019-09-07 19:01:19.035886 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] kXR_protocol successful (type: server [], protocol version 400) [2019-09-07 19:01:19.036090 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Sending out kXR_login request, username: condor, cgi: ?xrd.cc=cz&xrd.tz=1&xrd.appname=python2.7&xrd.info=&xrd.hostname=mff82.farm.particle.cz&xrd.rn=v4.9.1, dual-stack: true, private IPv4: true, private IPv6: false [2019-09-07 19:01:19.036109 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.036128 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:19.036157 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Wrote a message: (0x7c00f6c0), 125 bytes [2019-09-07 19:01:19.036170 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:19.036775 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:19.036797 +0200][Dump ][XRootDTransport ] [msg: 0x7c0009a0] Expecting 68 bytes of message body [2019-09-07 19:01:19.036808 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header, size: 8 [2019-09-07 19:01:19.036822 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received a message of 76 bytes [2019-09-07 19:01:19.036839 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Logged in, session: d614000079340000ab000000fd140000 [2019-09-07 19:01:19.036850 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Authentication is required: &P=gsi,v:10400,c:ssl,ca:e523eeaa.0|1db3b27f.0&P=unix [2019-09-07 19:01:19.036861 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Sending authentication data [2019-09-07 19:01:19.036901 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Trying to authenticate using gsi [2019-09-07 19:01:19.075426 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.075456 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:19.075482 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Wrote a message: (0x7c0109e0), 136 bytes [2019-09-07 19:01:19.075493 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:19.139754 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:19.139801 +0200][Dump ][XRootDTransport ] [msg: 0x7c0009a0] Expecting 3840 bytes of message body [2019-09-07 19:01:19.139814 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header, size: 8 [2019-09-07 19:01:19.139832 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received a message of 3848 bytes [2019-09-07 19:01:19.139845 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Sending more authentication data for gsi [2019-09-07 19:01:19.144538 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.144565 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:19.144606 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Wrote a message: (0x7c01e130), 9838 bytes [2019-09-07 19:01:19.144616 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:19.164251 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:19.164299 +0200][Dump ][XRootDTransport ] [msg: 0x7c0054e0] Expecting 0 bytes of message body [2019-09-07 19:01:19.164313 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header, size: 8 [2019-09-07 19:01:19.164324 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received a message of 8 bytes [2019-09-07 19:01:19.164423 +0200][Debug ][XRootDTransport ] [dpmpool19.farm.particle.cz:1095 #0.0] Authenticated with gsi. [2019-09-07 19:01:19.164437 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.164449 +0200][Debug ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Stream 0 connected. [2019-09-07 19:01:19.164461 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:19.164493 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Wrote a message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) (0x8c0071c0), 522 bytes [2019-09-07 19:01:19.164505 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Successfully sent message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) (0x8c0071c0). [2019-09-07 19:01:19.164517 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Message kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) has been successfully sent. [2019-09-07 19:01:19.164527 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Moving MsgHandler: 0x8c0080f0 (message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) ) from out-queu to in-queue. [2019-09-07 19:01:19.164545 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0.0] All messages consumed, disable uplink [2019-09-07 19:01:19.164554 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:19.395298 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:19.395346 +0200][Dump ][XRootDTransport ] [msg: 0x7c005590] Expecting 44 bytes of message body [2019-09-07 19:01:19.395360 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header for 0x7c005590 size: 8 [2019-09-07 19:01:19.395378 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message 0x7c005590 of 52 bytes [2019-09-07 19:01:19.395417 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Handling received message: 0x7c005590. [2019-09-07 19:01:19.395509 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Got a kXR_ok response to request kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) [2019-09-07 19:01:19.395555 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Calling MsgHandler: 0x8c0080f0 (message: kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) ) with status: [SUCCESS] . [2019-09-07 19:01:19.395574 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Parsing the response to kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) as OpenInfo [2019-09-07 19:01:19.395590 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Parsing StatInfo in response to kXR_open (file: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?dpm.chunk0=0,0,dpmpool19.farm.particle.cz:/mnt/fs2/atlas/2019-09-07/1M.01556.20190907190118.646902567.00000.117934.1567875679%3fsfn=%252Fdpm%252Ffarm.particle.cz%252Fhome%252Fatlas%252F1M.01556.20190907190118.646902567.00000&dpm.dhost=dpmpool19.farm.particle.cz&dpm.hv2=QDezmQ1RfOAnCbQOXyhSaA==&dpm.loc=1&dpm.nonce=25f07727&dpm.put=1&dpm.time=1567875679&oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, mode: 0644, flags: kXR_new kXR_open_updt kXR_posc kXR_async kXR_retstat ) [2019-09-07 19:01:19.395673 +0200][Debug ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Open has returned with status [SUCCESS] [2019-09-07 19:01:19.395688 +0200][Debug ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] successfully opened at dpmpool19.farm.particle.cz:1095, handle: 0x0, session id: 1 [2019-09-07 19:01:19.395726 +0200][Debug ][XRootD ] Redirect trace-back: [2019-09-07 19:01:19.395726 +0200][Debug ][XRootD ] 0. Redirected from: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 to: root://dpmpool19.farm.particle.cz:1095/ [2019-09-07 19:01:19.395740 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Destroying MsgHandler: 0x8c0080f0. [2019-09-07 19:01:19.395807 +0200][Debug ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Sending a read command for handle 0xf to localhost [2019-09-07 19:01:19.397183 +0200][Dump ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Got state response for message kXR_read (handle: 0x0f000000, offset: 0, size: 1048576) [2019-09-07 19:01:19.397295 +0200][Debug ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Sending a write command for handle 0x0 to dpmpool19.farm.particle.cz:1095 [2019-09-07 19:01:19.397348 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Sending message kXR_write (handle: 0x00000000, offset: 0, size: 1048576) [2019-09-07 19:01:19.397371 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] MsgHandler created: 0x8c008230 (message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) ). [2019-09-07 19:01:19.397420 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Sending message kXR_write (handle: 0x00000000, offset: 0, size: 1048576) (0x8c007020) through substream 0 expecting answer at 0 [2019-09-07 19:01:19.397438 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable write notifications, timeout: 15 [2019-09-07 19:01:19.397477 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:19.397505 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Will write the payload in one go with the header for message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) (0x8c007020). [2019-09-07 19:01:19.727585 +0200][Dump ][TaskMgr ] Running task: "FileTimer task" [2019-09-07 19:01:19.727646 +0200][Dump ][TaskMgr ] Will rerun task "FileTimer task" at [2019-09-07 19:01:34 +0200] [2019-09-07 19:01:23.306001 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:23.306078 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Will write the payload in one go with the header for message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) (0x8c007020). [2019-09-07 19:01:23.306103 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] WroteV a message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) (0x8c007020), 24 bytes [2019-09-07 19:01:23.306129 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] WroteV raw data: 448 bytes [2019-09-07 19:01:23.306141 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Successfully sent message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) (0x8c007020). [2019-09-07 19:01:23.306156 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Message kXR_write (handle: 0x00000000, offset: 0, size: 1048576) has been successfully sent. [2019-09-07 19:01:23.306169 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Moving MsgHandler: 0x8c008230 (message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) ) from out-queu to in-queue. [2019-09-07 19:01:23.306185 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0.0] All messages consumed, disable uplink [2019-09-07 19:01:23.306198 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:25.890592 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:25.890665 +0200][Dump ][XRootDTransport ] [msg: 0x7c005610] Expecting 0 bytes of message body [2019-09-07 19:01:25.890682 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header for 0x7c005610 size: 8 [2019-09-07 19:01:25.890701 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message 0x7c005610 of 8 bytes [2019-09-07 19:01:25.890717 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Handling received message: 0x7c005610. [2019-09-07 19:01:25.890816 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Got a kXR_ok response to request kXR_write (handle: 0x00000000, offset: 0, size: 1048576) [2019-09-07 19:01:25.890868 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Calling MsgHandler: 0x8c008230 (message: kXR_write (handle: 0x00000000, offset: 0, size: 1048576) ) with status: [SUCCESS] . [2019-09-07 19:01:25.890889 +0200][Dump ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Got state response for message kXR_write (handle: 0x00000000, offset: 0, size: 1048576) [2019-09-07 19:01:25.890924 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Destroying MsgHandler: 0x8c008230. [2019-09-07 19:01:25.891157 +0200][Debug ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Sending a close command for handle 0x0 to dpmpool19.farm.particle.cz:1095 [2019-09-07 19:01:25.891193 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Sending message kXR_close (handle: 0x00000000) [2019-09-07 19:01:25.891215 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] MsgHandler created: 0x8c008230 (message: kXR_close (handle: 0x00000000) ). [2019-09-07 19:01:25.891235 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Sending message kXR_close (handle: 0x00000000) (0x8c000c30) through substream 0 expecting answer at 0 [2019-09-07 19:01:25.891250 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Enable write notifications, timeout: 15 [2019-09-07 19:01:25.891339 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToWrite [2019-09-07 19:01:25.891442 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Wrote a message: kXR_close (handle: 0x00000000) (0x8c000c30), 24 bytes [2019-09-07 19:01:25.891465 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Successfully sent message: kXR_close (handle: 0x00000000) (0x8c000c30). [2019-09-07 19:01:25.891496 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Message kXR_close (handle: 0x00000000) has been successfully sent. [2019-09-07 19:01:25.891509 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Moving MsgHandler: 0x8c008230 (message: kXR_close (handle: 0x00000000) ) from out-queu to in-queue. [2019-09-07 19:01:25.891524 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0.0] All messages consumed, disable uplink [2019-09-07 19:01:25.891537 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Disable write notifications [2019-09-07 19:01:25.898869 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Got an event: ReadyToRead [2019-09-07 19:01:25.898922 +0200][Dump ][XRootDTransport ] [msg: 0x7c000d80] Expecting 0 bytes of message body [2019-09-07 19:01:25.898937 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message header for 0x7c000d80 size: 8 [2019-09-07 19:01:25.898952 +0200][Dump ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Received message 0x7c000d80 of 8 bytes [2019-09-07 19:01:25.898965 +0200][Dump ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Handling received message: 0x7c000d80. [2019-09-07 19:01:25.899006 +0200][Dump ][XRootD ] [dpmpool19.farm.particle.cz:1095] Got a kXR_ok response to request kXR_close (handle: 0x00000000) [2019-09-07 19:01:25.899039 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Calling MsgHandler: 0x8c008230 (message: kXR_close (handle: 0x00000000) ) with status: [SUCCESS] . [2019-09-07 19:01:25.899062 +0200][Debug ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Close returned from dpmpool19.farm.particle.cz:1095 with: [SUCCESS] [2019-09-07 19:01:25.899072 +0200][Dump ][File ] [0x8c006770@root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?oss.asize=1048576&xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Items in the fly 0, queued for recovery 0 [2019-09-07 19:01:25.899093 +0200][Debug ][ExDbgMsg ] [dpmpool19.farm.particle.cz:1095] Destroying MsgHandler: 0x8c008230. [2019-09-07 19:01:25.899176 +0200][Debug ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Sending a close command for handle 0xf to localhost [2019-09-07 19:01:25.899263 +0200][Debug ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Close returned from localhost with: [SUCCESS] [2019-09-07 19:01:25.899275 +0200][Dump ][File ] [0x8c001e20@file://localhost///tmp/1M?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix] Items in the fly 0, queued for recovery 0 [2019-09-07 19:01:25.899900 +0200][Debug ][JobMgr ] Stopping the job manager... [2019-09-07 19:01:25.899941 +0200][Dump ][JobMgr ] Stopping worker #0... [2019-09-07 19:01:25.900176 +0200][Dump ][JobMgr ] Worker #0 stopped [2019-09-07 19:01:25.900194 +0200][Debug ][JobMgr ] Job manager stopped [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:25.901310 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:25.901383 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:25.901471 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] URL: root://golias100.farm.particle.cz:1094///dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] Protocol: root [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] User Name: [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] Password: [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] Host Name: golias100.farm.particle.cz [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] Port: 1094 [2019-09-07 19:01:25.901517 +0200][Dump ][Utility ] Path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000 [2019-09-07 19:01:25.901587 +0200][Dump ][FileSystem ] [[log in to unmask]:1094] Sending kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) [2019-09-07 19:01:25.901602 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Sending message kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) [2019-09-07 19:01:25.901619 +0200][Debug ][ExDbgMsg ] [golias100.farm.particle.cz:1094] MsgHandler created: 0x1e99c20 (message: kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) ). [2019-09-07 19:01:25.901637 +0200][Dump ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Sending message kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) (0x1e9df40) through substream 0 expecting answer at 0 [2019-09-07 19:01:25.901651 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Enable write notifications, timeout: 15 [2019-09-07 19:01:25.901691 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToWrite [2019-09-07 19:01:25.901777 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Wrote a message: kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) (0x1e9df40), 150 bytes [2019-09-07 19:01:25.901803 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Successfully sent message: kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) (0x1e9df40). [2019-09-07 19:01:25.901815 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Message kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) has been successfully sent. [2019-09-07 19:01:25.901827 +0200][Debug ][ExDbgMsg ] [golias100.farm.particle.cz:1094] Moving MsgHandler: 0x1e99c20 (message: kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) ) from out-queu to in-queue. [2019-09-07 19:01:25.901841 +0200][Dump ][PostMaster ] [golias100.farm.particle.cz:1094 #0.0] All messages consumed, disable uplink [2019-09-07 19:01:25.901853 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Disable write notifications [2019-09-07 19:01:25.908127 +0200][Dump ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Got an event: ReadyToRead [2019-09-07 19:01:25.908183 +0200][Dump ][XRootDTransport ] [msg: 0x7c005610] Expecting 49 bytes of message body [2019-09-07 19:01:25.908199 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message header for 0x7c005610 size: 8 [2019-09-07 19:01:25.908220 +0200][Dump ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Received message 0x7c005610 of 57 bytes [2019-09-07 19:01:25.908233 +0200][Dump ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Handling received message: 0x7c005610. [2019-09-07 19:01:25.908317 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Got a kXR_ok response to request kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) [2019-09-07 19:01:25.908366 +0200][Debug ][ExDbgMsg ] [golias100.farm.particle.cz:1094] Calling MsgHandler: 0x1e99c20 (message: kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) ) with status: [SUCCESS] . [2019-09-07 19:01:25.908384 +0200][Dump ][XRootD ] [golias100.farm.particle.cz:1094] Parsing the response to kXR_stat (path: //dpm/farm.particle.cz/home/atlas/1M.01556.20190907190118.646902567.00000?xrd.gsiusrpxy=/tmp/x509up_u996&xrd.wantprot=gsi,unix, flags: none) as StatInfo: 758726472717303808 139703410778112 16 1567875679 [2019-09-07 19:01:25.908439 +0200][Debug ][ExDbgMsg ] [golias100.farm.particle.cz:1094] Destroying MsgHandler: 0x1e99c20. [2019-09-07 19:01:25.913645 +0200][Debug ][JobMgr ] Stopping the job manager... [2019-09-07 19:01:25.913691 +0200][Dump ][JobMgr ] Stopping worker #0... [2019-09-07 19:01:25.913777 +0200][Dump ][JobMgr ] Worker #0 stopped [2019-09-07 19:01:25.913791 +0200][Dump ][JobMgr ] Stopping worker #1... [2019-09-07 19:01:25.913912 +0200][Dump ][JobMgr ] Worker #1 stopped [2019-09-07 19:01:25.913925 +0200][Dump ][JobMgr ] Stopping worker #2... [2019-09-07 19:01:25.914014 +0200][Dump ][JobMgr ] Worker #2 stopped [2019-09-07 19:01:25.914028 +0200][Debug ][JobMgr ] Job manager stopped [2019-09-07 19:01:25.914038 +0200][Debug ][TaskMgr ] Stopping the task manager... [2019-09-07 19:01:25.914174 +0200][Debug ][TaskMgr ] Task manager stopped [2019-09-07 19:01:25.914189 +0200][Debug ][Poller ] Stopping the poller... [2019-09-07 19:01:25.914289 +0200][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: dpmpool19.farm.particle.cz:1095" [2019-09-07 19:01:25.914319 +0200][Debug ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Closing the socket [2019-09-07 19:01:25.914335 +0200][Debug ][Poller ] <[2001:718:401:6e03::1:82]:35474><--><[2001:718:401:6017:2::19]:1095> Removing socket from the poller [2019-09-07 19:01:25.914389 +0200][Debug ][PostMaster ] [dpmpool19.farm.particle.cz:1095 #0] Destroying stream [2019-09-07 19:01:25.914436 +0200][Debug ][AsyncSock ] [dpmpool19.farm.particle.cz:1095 #0.0] Closing the socket [2019-09-07 19:01:25.914459 +0200][Debug ][TaskMgr ] Requesting unregistration of: "TickGeneratorTask for: golias100.farm.particle.cz:1094" [2019-09-07 19:01:25.914470 +0200][Debug ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Closing the socket [2019-09-07 19:01:25.914481 +0200][Debug ][Poller ] <[2001:718:401:6e03::1:82]:51880><--><[2001:718:401:6017:2::1000]:1094> Removing socket from the poller [2019-09-07 19:01:25.914509 +0200][Debug ][PostMaster ] [golias100.farm.particle.cz:1094 #0] Destroying stream [2019-09-07 19:01:25.914524 +0200][Debug ][AsyncSock ] [golias100.farm.particle.cz:1094 #0.0] Closing the socket ######################################################################## Use REPLY-ALL to reply to list To unsubscribe from the XROOTD-L list, click the following link: https://listserv.slac.stanford.edu/cgi-bin/wa?SUBED1=XROOTD-L&A=1