[main] INFO zookeeper.ZooKeeper - Client environment:zookeeper.version=3.3.2-1031432, built on 11/05/2010 05:32 GMT [main] INFO zookeeper.ZooKeeper - Client environment:host.name=cnode1.iplantcollaborative.org [main] INFO zookeeper.ZooKeeper - Client environment:java.version=1.6.0_27 [main] INFO zookeeper.ZooKeeper - Client environment:java.vendor=Sun Microsystems Inc. [main] INFO zookeeper.ZooKeeper - Client environment:java.home=/usr/java/jdk1.6.0_27/jre [main] INFO zookeeper.ZooKeeper - Client environment:java.class.path=/usr/local/lib/porklock/porklock-1.2.1-SNAPSHOT-standalone.jar [main] INFO zookeeper.ZooKeeper - Client environment:java.library.path=/usr/java/jdk1.6.0_27/jre/lib/amd64/server:/usr/java/jdk1.6.0_27/jre/lib/amd64:/usr/java/jdk1.6.0_27/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib [main] INFO zookeeper.ZooKeeper - Client environment:java.io.tmpdir=/tmp [main] INFO zookeeper.ZooKeeper - Client environment:java.compiler= [main] INFO zookeeper.ZooKeeper - Client environment:os.name=Linux [main] INFO zookeeper.ZooKeeper - Client environment:os.arch=amd64 [main] INFO zookeeper.ZooKeeper - Client environment:os.version=2.6.18-348.18.1.el5 [main] INFO zookeeper.ZooKeeper - Client environment:user.name=condor [main] INFO zookeeper.ZooKeeper - Client environment:user.home=/var/lib/condor [main] INFO zookeeper.ZooKeeper - Client environment:user.dir=/var/lib/condor/iplant-de-jobs/sr320/7BCF4262-5922-4E1B-A28F-44B5D823D618 [porklock] [arguments] (get --user sr320 --source /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq --config /condor01/scratch/sr320/RNAseq2bedgraph_Fgo-2014-03-07-10-07-12.617/logs/irods-config -m ipc-execution-id,7BCF4262-5922-4E1B-A28F-44B5D823D618,UUID -m ipc-analysis-id,F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA,UUID) [porklock] [options] {:user sr320, :source /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq, :destination ., :config /condor01/scratch/sr320/RNAseq2bedgraph_Fgo-2014-03-07-10-07-12.617/logs/irods-config, :meta [(ipc-execution-id 7BCF4262-5922-4E1B-A28F-44B5D823D618 UUID) (ipc-analysis-id F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA UUID)], :help false} [porklock] [remnants] [] [main] WARN clojure-commons.config - CONFIG: porklock.irods-home = /iplant/home [main] WARN clojure-commons.config - CONFIG: porklock.irods-host = data.iplantcollaborative.org [main] WARN clojure-commons.config - CONFIG: porklock.irods-pass = ******** [main] WARN clojure-commons.config - CONFIG: porklock.irods-port = 1247 [main] WARN clojure-commons.config - CONFIG: porklock.irods-resc = [main] WARN clojure-commons.config - CONFIG: porklock.irods-user = ******** [main] WARN clojure-commons.config - CONFIG: porklock.irods-zone = iplant ================================================================================ env: {"irodsAuthFileName" "./.irods/.irodsA", "irodsEnvFile" "./.irods/.irodsEnv"} command: /usr/local2/icommands/iinit stdout: Enter your current iRODS password: [main] INFO connection.IRODSSimpleProtocolManager - creating simple protocol manager [main] INFO connection.IRODSSession - IRODS Session creation, loading default properties, these may be overridden... [main] INFO pub.IRODSFileSystem - IRODSfileSystem is initialized [main] INFO connection.AuthenticationFactoryImpl - instanceAuthMechanism() [main] INFO connection.AuthenticationFactoryImpl - authScheme:STANDARD [main] INFO connection.AuthenticationFactoryImpl - using standard auth [main] INFO connection.IRODSConnection - pipeline configuration:PipelineConfiguration irodsSocketTimeout:0 irodsParallelSocketTimeout:120 internalInputStreamBufferSize:0 internalOutputStreamBufferSize:-1 internalCacheBufferSize:65535 localFileOutputStreamBufferSize:0 localFileInputStreamBufferSize:0 defaultEncoding:UTF-8 inputToOutputCopyBufferByteSize:8192 instrument:false reconnect:false reconnect time in millis:600000 [main] INFO connection.IRODSConnection - using internal cache buffer of size:65535 [main] INFO connection.IRODSConnection - opening irods socket [main] INFO connection.IRODSConnection - connect() [main] INFO connection.IRODSConnection - connecting socket to agent [main] INFO connection.IRODSConnection - normal iRODS connection [main] INFO connection.IRODSConnection - connection to socket made... [main] INFO connection.IRODSConnection - default buffer on input stream [main] INFO connection.IRODSConnection - no buffer on output stream [main] INFO connection.IRODSConnection - socket opened successfully [main] INFO connection.IRODSCommands - reading message from irods [main] INFO connection.AuthMechanism - startup response:StartupResponseData: status:0 relVersion:rods3.3 apiVersion:d reconnPort:0 reconnAddr: cookie:0 [main] INFO connection.StandardIRODSAuth - authenticate [main] INFO connection.StandardIRODSAuth - sending standard irods password [main] INFO connection.IRODSCommands - reading message from irods [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO connection.StandardIRODSAuth - auth was successful [main] INFO connection.StandardIRODSAuth - auth response was:org.irods.jargon.core.connection.auth.AuthResponse@2c6c5356 [main] INFO connection.EnvironmentalInfoAccessor - getting irods server properties [main] INFO connection.DiscoveredServerPropertiesCache - now retriving server properties from cache with zone:iplant [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO connection.EnvironmentalInfoAccessor - server response obtained [main] INFO connection.IRODSCommands - IRODS server properties icat enabled?:ICAT_ENABLED Server boot time:1391616767 Rel version:rods3.3 API version:d zone:iplant [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isFile() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.UserGroupAOImpl - find user group with user name: sr320 [main] INFO pub.UserGroupAOImpl - query string: SELECT USER_GROUP_NAME,USER_GROUP_ID WHERE USER_NAME = 'sr320' [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResult() [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: IRODSQuery: queryString:SELECT USER_GROUP_NAME,USER_GROUP_ID WHERE USER_NAME = 'sr320' numberOfResultsDesired:500 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 2 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResult() [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: IRODSQuery: queryString:select USER_ID where USER_NAME = 'public' numberOfResultsDesired:50000 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 1 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResult() [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: IRODSQuery: queryString:select DATA_ACCESS_TYPE where COLL_NAME = '/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419' and DATA_NAME = 'Fgo_2.fq' and DATA_ACCESS_USER_ID = '10004' numberOfResultsDesired:50000 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - response from IRODS call indicates no rows found [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResult() [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: IRODSQuery: queryString:select USER_ID where USER_NAME = 'sr320' numberOfResultsDesired:50000 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 1 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResult() [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: IRODSQuery: queryString:select DATA_ACCESS_TYPE where COLL_NAME = '/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419' and DATA_NAME = 'Fgo_2.fq' and DATA_ACCESS_USER_ID = '73068066' numberOfResultsDesired:50000 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 1 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [porklock] Metadata tuples for /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq are ((ipc-execution-id 7BCF4262-5922-4E1B-A28F-44B5D823D618 UUID) (ipc-analysis-id F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA UUID)) [porklock] Size of tuple (ipc-execution-id 7BCF4262-5922-4E1B-A28F-44B5D823D618 UUID) is 3 [porklock] Might be adding metadata to /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq (ipc-execution-id 7BCF4262-5922-4E1B-A28F-44B5D823D618 UUID) [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.DataObjectAOImpl - findMetadataValuesForDataObject: /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - setting file name, given path = Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.FileCatalogObjectAOImpl - retrieveObjStat() [main] INFO pub.FileCatalogObjectAOImpl - irodsAbsolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: org.irods.jargon.core.query.IRODSGenQueryFromBuilder@21453d72 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 3 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [porklock] AVU? /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq () [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.DataObjectAOImpl - findMetadataValuesForDataObject: /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - setting file name, given path = Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.FileCatalogObjectAOImpl - retrieveObjStat() [main] INFO pub.FileCatalogObjectAOImpl - irodsAbsolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: org.irods.jargon.core.query.IRODSGenQueryFromBuilder@5b4bc4e6 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 3 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [porklock] Adding metadata ipc-execution-id 7BCF4262-5922-4E1B-A28F-44B5D823D618 /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.DataObjectAOImpl - adding avu metadata to data object: avu data: attrib:ipc-execution-id value:7BCF4262-5922-4E1B-A28F-44B5D823D618 units:UUID [main] INFO pub.DataObjectAOImpl - absolute path: /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO pub.FileCatalogObjectAOImpl - resoveAbsolutePathViaObjStat() [main] INFO pub.FileCatalogObjectAOImpl - retrieveObjStat() [main] INFO pub.FileCatalogObjectAOImpl - irodsAbsolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419 dataId:103185990 specColType:NORMAL objectType:COLLECTION collectionPath: objectPath: checksum: ownerName:de-irods ownerZone:iplant objSize:0 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:37:08 MST 2014 modifiedAt:Fri Mar 07 09:37:08 MST 2014 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [porklock] Size of tuple (ipc-analysis-id F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA UUID) is 3 [porklock] Might be adding metadata to /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq (ipc-analysis-id F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA UUID) [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.DataObjectAOImpl - findMetadataValuesForDataObject: /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - setting file name, given path = Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.FileCatalogObjectAOImpl - retrieveObjStat() [main] INFO pub.FileCatalogObjectAOImpl - irodsAbsolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: org.irods.jargon.core.query.IRODSGenQueryFromBuilder@7ee41d4a [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 4 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [porklock] AVU? /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq () [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.DataObjectAOImpl - findMetadataValuesForDataObject: /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - setting file name, given path = Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.FileCatalogObjectAOImpl - retrieveObjStat() [main] INFO pub.FileCatalogObjectAOImpl - irodsAbsolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO pub.IRODSGenQueryExecutorImpl - executeIRODSQueryAndCloseResultInZone() [main] INFO pub.IRODSGenQueryExecutorImpl - query: org.irods.jargon.core.query.IRODSGenQueryFromBuilder@62d1b72d [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO query.GenQueryProcessor - continuation value: 0 [main] INFO query.GenQueryProcessor - total records:0 [main] INFO query.QueryResultProcessingUtils - rows returned from iRODS query: 4 [main] INFO query.GenQueryProcessor - auto closing result set [main] INFO query.GenQueryProcessor - getting more results for query [main] INFO query.GenQueryProcessor - no results to close, ignore [porklock] Adding metadata ipc-analysis-id F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - setting file name, given path = /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - detected local separator = / [main] INFO io.IRODSFileImpl - file name was set as: Fgo_2.fq [main] INFO io.IRODSFileImpl - isDirectory() for path:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO io.IRODSFileImpl - looking up objStat, not cached in file [main] INFO pub.IRODSFileSystemAOImpl - getObjStat(final String irodsAbsolutePath) [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq dataId:103186122 specColType:NORMAL objectType:DATA_OBJECT collectionPath: objectPath: checksum:c056bf4b3dec4b5ee0fb5efc4ded85ed ownerName:sr320 ownerZone:iplant objSize:3853060828 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:55:05 MST 2014 modifiedAt:Fri Mar 07 09:55:05 MST 2014 [main] INFO pub.DataObjectAOImpl - adding avu metadata to data object: avu data: attrib:ipc-analysis-id value:F7626782-7D3E-4AF0-BAEE-33C0EB8D84FA units:UUID [main] INFO pub.DataObjectAOImpl - absolute path: /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq [main] INFO pub.FileCatalogObjectAOImpl - resoveAbsolutePathViaObjStat() [main] INFO pub.FileCatalogObjectAOImpl - retrieveObjStat() [main] INFO pub.FileCatalogObjectAOImpl - irodsAbsolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods [main] INFO pub.CollectionAndDataObjectListAndSearchAOImpl - objStat: absolutePath:/iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419 dataId:103185990 specColType:NORMAL objectType:COLLECTION collectionPath: objectPath: checksum: ownerName:de-irods ownerZone:iplant objSize:0 cacheDir: cacheDirty:false createdAt:replNumber:0Fri Mar 07 09:37:08 MST 2014 modifiedAt:Fri Mar 07 09:37:08 MST 2014 [main] INFO connection.IRODSCommands - calling irods function with byte array [main] INFO connection.IRODSCommands - reading message from irods ================================================================================ env: {"clientUserName" "sr320", "irodsAuthFileName" "./.irods/.irodsA", "irodsEnvFile" "./.irods/.irodsEnv"} command: /usr/local2/icommands/iget -f -P /iplant/home/sr320/Cgigas_v9/Zhang/fastq/Fgo-2014-03-07-09-35-03.419/Fgo_2.fq ./ stdout: 0/1 - 0.00% of files done 0.000/3674.481 MB - 0.00% of file sizes done Processing Fgo_2.fq - 3674.481 MB 2014-03-07.10:09:21 Fgo_2.fq - 39.999/3674.481 MB - 1.09% done 2014-03-07.10:09:23 Fgo_2.fq - 599.986/3674.481 MB - 16.33% done 2014-03-07.10:09:29 Fgo_2.fq - 1239.972/3674.481 MB - 33.75% done 2014-03-07.10:10:43 Fgo_2.fq - 1799.959/3674.481 MB - 48.99% done 2014-03-07.10:12:02 Fgo_2.fq - 2439.944/3674.481 MB - 66.40% done 2014-03-07.10:12:20 Fgo_2.fq - 3059.251/3674.481 MB - 83.26% done 2014-03-07.10:12:34 Fgo_2.fq - 3674.481/3674.481 MB - 100.00% done 2014-03-07.10:12:46 [main] INFO connection.IRODSSession - closing all irods sessions [main] INFO connection.IRODSCommands - closing connection [main] INFO connection.IRODSCommands - shutdown()...check if executor service is running for reconnect [main] INFO connection.IRODSCommands - shutting down, need to send disconnect to irods [main] INFO connection.IRODSCommands - sending disconnect message [main] INFO connection.IRODSConnection - shutting down connection: true [main] INFO connection.IRODSConnection - is connected for : irodsSimpleConnection/main/1394212160648 [main] INFO connection.IRODSConnection - now disconnected