[PGSCHED] Debug ArchiveRequest::insert()
Archival request is sent to be queued by the ctafrontend
[1], but no new row appears in the PGSCHED DB [2]. The method ArchiveRequest::insert()
does print a success to the log despite not having done what is required. This needs to be debugged.
[1]
Jan 25 13:00:06.042022 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="In WorkflowEvent::WorkflowEvent(): received event." user="ctaeos@ctafrontend" eventType="CREATE" eosInstance="ctaeos" diskFilePath="/eos/ctaeos/cta/74d67cbc5b92498ebfe48f992440b311" diskFileId="11"
Jan 25 13:00:06.051252 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="Checked request and got next archive file ID" user="ctaeos@ctafrontend" instanceName="ctaeos" username="user1" usergroup="eosusers" storageClass="ctaStorageClass" fileId="4294967296" catalogueTime="0.008968" schedulerDbTime="0.008968"
Jan 25 13:00:06.051332 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="In WorkflowEvent::processCREATE(): assigning new archive file ID." user="ctaeos@ctafrontend" diskFileId="11" diskFilePath="/eos/ctaeos/cta/74d67cbc5b92498ebfe48f992440b311" fileId="4294967296" schedulerTime="0.009105"
Jan 25 13:00:06.063001 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="In WorkflowEvent::WorkflowEvent(): received event." user="ctaeos@ctafrontend" eventType="CLOSEW" eosInstance="ctaeos" diskFilePath="/eos/ctaeos/cta/74d67cbc5b92498ebfe48f992440b311" diskFileId="11"
Jan 25 13:00:06.071659 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="In ArchiveRequest::insert(): added job to queue." user="ctaeos@ctafrontend" requesterInstance="ctaeos.nsdev.svc.cluster.local:fst" fileId="4294967296" mountId="0" status="AJS_ToTransferForUser" tapePool="ctasystest" mountPolicy="ctasystest" priority="1" minArchiveRequestAge="1" archiveFileId="4294967296" sizeInBytes="381" copyNb="1" startTime="1706184006" checksumBlob="[ { 'ADLER32',0x9c8d7679 } ]" creationTime="1706184006" diskInstance="ctaeos" diskFileId="11" diskFileOwnerUid="11001" diskFileGid="1100" diskFilePath="/eos/ctaeos/cta/74d67cbc5b92498ebfe48f992440b311" archiveReportUrl="eosQuery://ctaeos.nsdev.svc.cluster.local//eos/wfe/passwd?mgm.pcmd=event&mgm.fid=b&mgm.logid=cta&mgm.event=sync::archived&mgm.workflow=default&mgm.path=/dummy_path&mgm.ruid=0&mgm.rgid=0&cta_archive_file_id=4294967296" archiveErrorReportUrl="eosQuery://ctaeos.nsdev.svc.cluster.local//eos/wfe/passwd?mgm.pcmd=event&mgm.fid=b&mgm.logid=cta&mgm.event=sync::archive_failed&mgm.workflow=default&mgm.path=/dummy_path&mgm.ruid=0&mgm.rgid=0&cta_archive_file_id=4294967296&mgm.errmsg=" requesterName="user1" requesterGroup="eosusers" srcUrl="root://ctaeos.nsdev.svc.cluster.local//eos/ctaeos/cta/74d67cbc5b92498ebfe48f992440b311?eos.lfn=fxid:b" storageClass="ctaStorageClass" retriesWithinMount="0" totalRetries="0" lastMountWithFailure="0" maxTotalRetries="2"
Jan 25 13:00:06.073146 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="Queued archive request" user="ctaeos@ctafrontend" requesterInstance="ctaeos.nsdev.svc.cluster.local:fst" fileId="4294967296" instanceName="ctaeos" storageClass="ctaStorageClass" diskFileID="11" fileSize="381" tapePool1="ctasystest" policyName="ctasystest" policyArchiveMinAge="1" policyArchivePriority="1" diskFilePath="/eos/ctaeos/cta/74d67cbc5b92498ebfe48f992440b311" diskFileOwnerUid="11001" diskFileGid="1100" archiveReportURL="eosQuery://ctae[...]cta_archive_file_id=4294967296" archiveErrorReportURL="eosQuery://ctae[...]file_id=4294967296&mgm.errmsg=" creationHost="ctafrontend" creationTime="1706184006" creationUser="ctaeos" requesterName="user1" requesterGroup="eosusers" srcURL="root://ctaeos.n[...]fe48f992440b311?eos.lfn=fxid:b" catalogueTime="0.000050" schedulerDbTime="0.008750" checksumType="ADLER32" checksumValue="9c8d7679"
Jan 25 13:00:06.073315 ctafrontend cta-frontend: LVL="INFO" PID="320" TID="346" MSG="In WorkflowEvent::processCLOSEW(): queued file for archive." user="ctaeos@ctafrontend" requesterInstance="ctaeos.nsdev.svc.cluster.local:fst" schedulerTime="0.009114" archiveRequestId="?"
[2]
postgres-sched=# select * from archive_job_queue;
job_id | archive_reqid | mount_id | status | tape_pool | mount_
policy | priority | min_archive_request_age | archive_file_id | size_in_bytes |
copy_nb | start_time | checksumblob | creation_time | disk_instance |
disk_file_id | disk_file_owner_uid | disk_file_gid | disk_file_
path |
archive_report_url
|
archive_error_report_url
|
requester_name | requester_group |
src_url | storage_class | ret
ries_within_mount | total_retries | last_mount_with_failure | max_total_retries
| max_retries_within_mount | max_report_retries | total_report_retries | archive
_jobfaillogs_pb | is_repack | repack_reqid | repack_filebuf_url | repack_fseq |
repack_dest_vid | is_reportdecided | vid
--------+---------------+----------+-----------------------+------------+-------
-------+----------+-------------------------+-----------------+---------------+-
--------+------------+------------------------+---------------+---------------+-
-------------+---------------------+---------------+----------------------------
----------------------+---------------------------------------------------------
--------------------------------------------------------------------------------
--------------------------------------------------------------------------------
postgres-sched=# \q