Solved: check the CAP, reinventory!

r1 · ‎06-21-2010

we have daily Vault job that runs everyday that also start catalog backup , the catalog job completes but the daily vault hangs at "Starting Eject of 3 media"

Here is a copy of the details.log and activoty montior job . by the way , the log and activitiy monitor are off by one hour and that is only because the remote server is in a remote locatoon and i am running the gui on my machine localy .

I can see the three media in the eject list that needs to be eject but in activity monitor it just sets never and never die , i have to kill it fmanualy , last weekend it ran for 3 days , i also have eject immeditly enabled not deffered . anyways , anyone , this was working and i don't believe anything have been recently changed . Thanks in advance

ACTIVITY MONITOR
Jun 21, 2010 2:26:15 PM - vault waiting for session ID lock
Jun 21, 2010 2:26:17 PM - vault session ID lock acquired
Jun 21, 2010 2:26:17 PM - vault session ID lock released
Jun 21, 2010 2:26:13 PM - requesting resource tmnaim01.NBVAULT.MAXJOBS
Jun 21, 2010 2:26:13 PM - requesting resource tmnaim01.NBU_POLICY.MAXJOBS.Vault_Daily
Jun 21, 2010 2:26:13 PM - granted resource tmnaim01.NBVAULT.MAXJOBS
Jun 21, 2010 2:26:13 PM - granted resource tmnaim01.NBU_POLICY.MAXJOBS.Vault_Daily
Jun 21, 2010 2:26:13 PM - estimated 0 kbytes needed
Jun 21, 2010 2:26:14 PM - begin Parent Job
Jun 21, 2010 2:26:14 PM - begin Vault: Start Notify Script
Jun 21, 2010 2:26:14 PM - started process RUNCMD (pid=9099)
Jun 21, 2010 2:26:14 PM - ended process 0 (pid=9099)
Operation Status: 0
Jun 21, 2010 2:26:14 PM - end Vault: Start Notify Script; elapsed time 0:00:00
Jun 21, 2010 2:26:14 PM - begin Vault: Execute Script
Jun 21, 2010 2:26:14 PM - started process bpbrm (pid=9108)
Jun 21, 2010 2:26:15 PM - requesting resource tmnaim01.VAULT_CREATE_SESSION_ID.LOCK_TLD(0)_tmna_vault
Jun 21, 2010 2:26:15 PM - granted resource tmnaim01.VAULT_CREATE_SESSION_ID.LOCK_TLD(0)_tmna_vault
Jun 21, 2010 2:26:26 PM - Duplication skipped
Jun 21, 2010 2:26:26 PM - vault waiting for assign slot lock
Jun 21, 2010 2:26:26 PM - requesting resource tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
Jun 21, 2010 2:26:26 PM - granted resource tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
Jun 21, 2010 2:26:28 PM - vault assign slot lock acquired
Jun 21, 2010 2:26:28 PM - vault assign slot lock released
Jun 21, 2010 2:26:28 PM - begin Catalog Backup
Jun 21, 2010 2:26:28 PM - Starting Catalog Backup for Policy: NBU-Catalog-bkup Schedule: vault-catalog
Jun 21, 2010 3:17:22 PM - Catalog Backup completed
Jun 21, 2010 3:17:22 PM - end Catalog Backup; elapsed time 0:50:54
Jun 21, 2010 3:17:22 PM - vault waiting for assign slot lock
Jun 21, 2010 3:17:24 PM - vault assign slot lock acquired
Jun 21, 2010 3:17:24 PM - vault assign slot lock released
Jun 21, 2010 3:17:25 PM - before eject, waiting for media to be unmounted; sleeping for 360 seconds
Jun 21, 2010 3:17:22 PM - requesting resource tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
Jun 21, 2010 3:17:22 PM - granted resource tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
Jun 21, 2010 3:23:25 PM - starting eject operation
Jun 21, 2010 3:23:25 PM - begin Eject and Report
Jun 21, 2010 3:23:25 PM - connecting
Jun 21, 2010 3:23:25 PM - connected; connect time: 0:00:00
Jun 21, 2010 3:23:25 PM - vault waiting for eject lock
Jun 21, 2010 3:23:27 PM - vault eject lock acquired
Jun 21, 2010 3:23:25 PM - requesting resource tmnaim01.VAULT_EJECT.LOCK_0
Jun 21, 2010 3:23:25 PM - granted resource tmnaim01.VAULT_EJECT.LOCK_0
Jun 21, 2010 3:23:28 PM - Starting Eject of 3 media

-------------------Details.log -------------
15:26:17.293 [9109] <4> vltrun@startSession: Session Information
RVP   = daily_profile
SID   = 1293
StartTime   = 2010.06.21 15:26:17 (1277148377)
15:26:17.294 [9109] <2> vltrun@VaultJobMonitor::_send_jobdata^1293: SENT JobDataEx_t to JOBD at 1277148377
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: VAULT_PREFERENCES NotificationEmail=nabil.alomar@thementornetwork.com EjectNotificationEmail=nabil.alomar@thementornetwork.com SortOnExpiryDate=NO MGOImageSelectDays=2 LastMod=[TS=12651282402379540]
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: ALIASES
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: RETENTION_MAP
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=0, NEW=0
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=1, NEW=1
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=2, NEW=2
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=3, NEW=3
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=4, NEW=4
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=5, NEW=5
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=6, NEW=6
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=7, NEW=7
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=8, NEW=8
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=9, NEW=9
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=10, NEW=10
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=11, NEW=11
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=12, NEW=12
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=13, NEW=13
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=14, NEW=14
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=15, NEW=15
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=16, NEW=16
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=17, NEW=17
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=18, NEW=18
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=19, NEW=19
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=20, NEW=20
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=21, NEW=21
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=22, NEW=22
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=23, NEW=23
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: OLD=24, NEW=24
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: REPORTS
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=0 Title=Picking List for Robot Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com, nocmonitor@navisite.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=1 Title=Distribution List for Vault Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=2 Title=Detailed Distribution List for Vault Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=3 Title=Summary Distribution List for Vault Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=4 Title=Picking List for Vault Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=5 Title=Distribution List for Robot Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=6 Title=Vault Inventory Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=7 Title=Off-site Inventory Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=8 Title=All Media Inventory Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=9 Title=Recovery Report for Vault Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com Start=0 End=7
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: Report Name=10 Title=Non-vaulted Images Option=YES File=/tmp Printer= Email=nabil.alomar@thementornetwork.com
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: ROBOT Name=TLD(0) Id=1 RobotNumber=0 RobotType=TLD RoboticControlHost=tmnaim01 LastModSec=[TS=11919402599663690] Use_VP_ENE=YES
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: VAULT Name=tmna_vault Vendor=xxxxx Id=2 RobotId=1 CustomerID= RobotVolumeGroup=000_00000_TLD OffsiteVolumeGroup=My_offsite_volumes VaultContainers=NO VaultSeed=1 LastMod=[TS=11919553856713050]
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: MAP
15:26:17.294 [9109] <4> vltrun@save_vault_conf()^1293: RETENTION_MAP
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: PROFILE Name=daily_profile Id=3 VaultId=2 RobotId=1 LastMod=[TS=12651281322538170]
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: SELECTION StartDay=14 StartHour=0 EndDay=2 EndHour=0
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: ATTRIBUTE_FILTER = YES
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: CLIENT Attributes: ClientOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: CLIENT:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: BACKUP_TYPE: Attributes: BackupTypeOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: BACKUP_TYPE:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: MEDIA_SERVER Attributes: MediaServerOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: MEDIA_SERVER:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: CLASS Attributes: ClassOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: CLASS:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: SCHEDULE Attributes: ScheduleOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: SCHEDULE:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: RETENTION_LEVEL: Attributes: RetentionLevelOption=ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: RETENTION_LEVEL:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: LOCATION_FILTER = YES
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: SOURCE_VOLUME_GROUP: Attributes: SourceVolumeGroupOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: SOURCE_VOLUME_GROUP:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: VOLUME_POOL: Attributes: VolumePoolOption=INCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: VOLUME_POOL:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: BASIC_DISK: Attributes: BasicDiskOption=EXCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: BASIC_DISK:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: DiskGroup: Attributes: DiskGroupOption=EXCLUDE_ALL
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: DiskGroup:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: DUPLICATION Skip=YES
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: CATALOG_BACKUP Skip=NO
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: CatalogBackupPolicy=NBU-Catalog-bkup
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: VaultCatalogBackupSchedule=vault-catalog
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: EJECT Skip=NO
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: EjectMode=Auto
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: SuspendMode=LATER
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: POOLS: 2wk-offsite 3wk-offsite catalog-offsite
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: AUXILIARY_POOLS:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: Use_RBT_or_VP_ENE=YES
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293:
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: REPORTS HEADER
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: ReportsMode=Auto
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: IMFile=
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: ReportsHeader=
15:26:17.295 [9109] <4> vltrun@save_vault_conf()^1293: UseGlobalRptsDist=YES
15:26:17.295 [9109] <2> vltrun@main: start vault session
15:26:17.295 [9109] <4> vltrun@VltSession::runSession^1293: Starting to execute session steps P=daily_profile SID=1293 JOB_ID=77714
15:26:17.295 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:17.295 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = start_notify
StartTime  = 2010.06.21 15:26:17 (1277148377)
15:26:17.295 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: start_notify
15:26:17.295 [9109] <2> vltrun@VOpExecuteScript::doOperation^1293: CALLING /usr/openv/netbackup/bin/vlt_start_notify script; robot:0, vault:tmna_vault, profile:daily_profile, and sid:1293
15:26:17.295 [9109] <2> vltrun@VOpExecuteScript::doOperation^1293: Vault Notify Script=/usr/openv/netbackup/bin/vlt_start_notify RV=0
15:26:17.295 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148377
15:26:17.295 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=1 to JOBD
15:26:17.295 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:17 (1277148377)
Elapsed   :0:0
15:26:17.295 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:17.295 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = changegroup_torobot
StartTime  = 2010.06.21 15:26:17 (1277148377)
15:26:17.295 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: changegroup_torobot
15:26:17.295 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=changegroup_torobot
15:26:17.295 [9109] <2> vltrun@BldRobotInventory()^1293: entering function
15:26:17.295 [9109] <4> vltrun@BldRobotInventory()^1293: Executing command: /usr/openv/volmgr/bin/vmcheckxxx -rt tld -rn 0 -rh tmnaim01 -h tmnaim01 -list 2>&-
15:26:25.159 [9109] <4> vltrun@BldRobotInventory()^1293: Found 23 items in Robot
15:26:25.159 [9109] <2> vltrun@QueryByBarcode^1293: BC=000049
15:26:25.271 [9109] <2> vltrun@QueryByBarcode^1293: BC=000099
15:26:25.277 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000099) is un-assigned or expired, clearing vault fields
15:26:25.277 [9109] <2> vltrun@QueryByBarcode^1293: BC=000178
15:26:25.283 [9109] <2> vltrun@QueryByBarcode^1293: BC=000180
15:26:25.289 [9109] <2> vltrun@QueryByBarcode^1293: BC=000182
15:26:25.295 [9109] <2> vltrun@QueryByBarcode^1293: BC=000184
15:26:25.301 [9109] <2> vltrun@QueryByBarcode^1293: BC=000185
15:26:25.307 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000185) is un-assigned or expired, clearing vault fields
15:26:25.307 [9109] <2> vltrun@QueryByBarcode^1293: BC=000194
15:26:25.313 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000194) is un-assigned or expired, clearing vault fields
15:26:25.313 [9109] <2> vltrun@QueryByBarcode^1293: BC=000204
15:26:25.319 [9109] <2> vltrun@QueryByBarcode^1293: BC=000205
15:26:25.326 [9109] <2> vltrun@QueryByBarcode^1293: BC=000224
15:26:25.331 [9109] <2> vltrun@QueryByBarcode^1293: BC=000232
15:26:25.337 [9109] <2> vltrun@QueryByBarcode^1293: BC=000237
15:26:25.343 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000237) is un-assigned or expired, clearing vault fields
15:26:25.343 [9109] <2> vltrun@QueryByBarcode^1293: BC=000242
15:26:25.349 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000242) is un-assigned or expired, clearing vault fields
15:26:25.349 [9109] <2> vltrun@QueryByBarcode^1293: BC=000243
15:26:25.355 [9109] <2> vltrun@QueryByBarcode^1293: BC=000268
15:26:25.361 [9109] <2> vltrun@QueryByBarcode^1293: BC=000269
15:26:25.367 [9109] <2> vltrun@QueryByBarcode^1293: BC=000272
15:26:25.373 [9109] <2> vltrun@QueryByBarcode^1293: BC=000273
15:26:25.378 [9109] <2> vltrun@QueryByBarcode^1293: BC=000281
15:26:25.384 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000281) is un-assigned or expired, clearing vault fields
15:26:25.384 [9109] <2> vltrun@QueryByBarcode^1293: BC=000284
15:26:25.390 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000284) is un-assigned or expired, clearing vault fields
15:26:25.390 [9109] <2> vltrun@QueryByBarcode^1293: BC=000287
15:26:25.396 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000287) is un-assigned or expired, clearing vault fields
15:26:25.396 [9109] <2> vltrun@QueryByBarcode^1293: BC=000293
15:26:25.403 [9109] <8> vltrun@DoChangeGroup^1293: Tape (MID=000293) is un-assigned or expired, clearing vault fields
15:26:25.403 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148385
15:26:25.403 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=2 to JOBD
15:26:25.403 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:25 (1277148385)
Elapsed   :0:8
15:26:25.403 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:25.403 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = select
StartTime  = 2010.06.21 15:26:25 (1277148385)
15:26:25.403 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: select
15:26:25.403 [9109] <2> vltrun@VOpSelect::doOperation^1293: OP=select VLT=tmna_vault
15:26:25.403 [9109] <2> vltrun@LoadMediaByVolumeGroup^1293: #VolGrps=1
15:26:25.596 [9109] <4> vltrun@LoadMediaByVolumeGroup^1293: GOT #Vols=23 of #VolGrp=1
15:26:25.596 [9109] <2> vltrun@UsingStagingFltrOption^1293: Profile:0/tmna_vault/daily_profile
15:26:25.596 [9109] <2> vltrun@UsingStagingFltrOption^1293: TouchFile does not exist, continuing with default vault location filter behavior
15:26:25.596 [9109] <2> vltrun@FilterImages^1293: STS=1275938785 ETS=1276975585 BKUPTYPE=9 FLTR=4
15:26:25.596 [9109] <4> vltrun@FilterImages^1293: Selection Window START=06/07/10 15:26:25 END=06/19/10 15:26:25
15:26:25.596 [9109] <4> vltrun@ImageInterface::init_query^1293: TS=(1275938785,1276975585) BPType=9 PLCY=
15:26:25.855 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276938903
15:26:25.855 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276938903 Rejected - No fragment in Robot
15:26:25.855 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276852500
15:26:25.855 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276852500 Rejected - No fragment in Robot
15:26:25.855 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276766100
15:26:25.855 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276766100 Rejected - No fragment in Robot
15:26:25.855 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276679700
15:26:25.855 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276679700 Rejected - No fragment in Robot
15:26:25.856 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276593300
15:26:25.856 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276593300 Rejected - No fragment in Robot
15:26:25.856 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276416000
15:26:25.856 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276416000 Rejected - No fragment in Robot
15:26:25.856 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276334100
15:26:25.856 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276334100 Rejected - No fragment in Robot
15:26:25.858 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276247700
15:26:25.858 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276247700 Rejected - No fragment in Robot
15:26:25.858 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276161300
15:26:25.858 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276161300 Rejected - No fragment in Robot
15:26:25.858 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1276074900
15:26:25.858 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1276074900 Rejected - No fragment in Robot
15:26:25.858 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn001_1275988500
15:26:25.859 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn001_1275988500 Rejected - No fragment in Robot
15:26:25.859 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276938900
15:26:25.859 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276938900 Rejected - No fragment in Robot
15:26:25.859 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276852501
15:26:25.859 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276852501 Rejected - No fragment in Robot
15:26:25.859 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276766101
15:26:25.859 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276766101 Rejected - No fragment in Robot
15:26:25.861 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276679701
15:26:25.861 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276679701 Rejected - No fragment in Robot
15:26:25.861 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276593301
15:26:25.861 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276593301 Rejected - No fragment in Robot
15:26:25.861 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276416001
15:26:25.861 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276416001 Rejected - No fragment in Robot
15:26:25.861 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276334101
15:26:25.862 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276334101 Rejected - No fragment in Robot
15:26:25.862 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276247701
15:26:25.862 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276247701 Rejected - No fragment in Robot
15:26:25.862 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276161301
15:26:25.862 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276161301 Rejected - No fragment in Robot
15:26:25.862 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1276074901
15:26:25.862 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1276074901 Rejected - No fragment in Robot
15:26:25.864 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn002_1275988501
15:26:25.864 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn002_1275988501 Rejected - No fragment in Robot
15:26:25.865 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276938902
15:26:25.865 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276938902 Rejected - No fragment in Robot
15:26:25.865 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276852502
15:26:25.865 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276852502 Rejected - No fragment in Robot
15:26:25.865 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276766102
15:26:25.865 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276766102 Rejected - No fragment in Robot
15:26:25.865 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276679702
15:26:25.865 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276679702 Rejected - No fragment in Robot
15:26:25.865 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276593302
15:26:25.865 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276593302 Rejected - No fragment in Robot
15:26:25.865 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276416002
15:26:25.865 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276416002 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276334102
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276334102 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276247702
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276247702 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276161302
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276161302 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1276074902
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1276074902 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=edimn004_1275988502
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: edimn004_1275988502 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276833600
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276833600 Rejected - No fragment in Robot
15:26:25.868 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276747691
15:26:25.868 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276747691 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276660800
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276660800 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276574400
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276574400 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276488000
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276488000 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276229204
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276229204 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276142786
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276142786 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1276056000
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1276056000 Rejected - No fragment in Robot
15:26:25.871 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=epicenter2_1275969600
15:26:25.871 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: epicenter2_1275969600 Rejected - No fragment in Robot
15:26:25.874 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=serdesk1-bkp_1276840800
15:26:25.874 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: serdesk1-bkp_1276840800 Rejected - No fragment in Robot
15:26:25.874 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=serdesk1-bkp_1276236000
15:26:25.874 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: serdesk1-bkp_1276236000 Rejected - No fragment in Robot
15:26:25.874 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276938901
15:26:25.874 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276938901 Rejected - No fragment in Robot
15:26:25.874 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276852503
15:26:25.874 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276852503 Rejected - No fragment in Robot
15:26:25.874 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276766103
15:26:25.874 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276766103 Rejected - No fragment in Robot
15:26:25.874 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276679703
15:26:25.874 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276679703 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276593303
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276593303 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276416003
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276416003 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276334103
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276334103 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276247703
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276247703 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276161303
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276161303 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1276074903
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1276074903 Rejected - No fragment in Robot
15:26:25.877 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab01_1275988503
15:26:25.877 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab01_1275988503 Rejected - No fragment in Robot
15:26:25.880 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276927200
15:26:25.880 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276927200 Rejected - No fragment in Robot
15:26:25.880 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276833601
15:26:25.880 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276833601 Rejected - No fragment in Robot
15:26:25.880 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276747846
15:26:25.880 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276747846 Rejected - No fragment in Robot
15:26:25.880 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276660801
15:26:25.880 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276660801 Rejected - No fragment in Robot
15:26:25.880 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276574556
15:26:25.880 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276574556 Rejected - No fragment in Robot
15:26:25.880 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276488001
15:26:25.880 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276488001 Rejected - No fragment in Robot
15:26:25.881 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276322400
15:26:25.881 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276322400 Rejected - No fragment in Robot
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276229242
15:26:25.884 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276229242 Rejected - No fragment in Robot
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276142865
15:26:25.884 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276142865 Rejected - No fragment in Robot
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1276056001
15:26:25.884 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1276056001 Rejected - No fragment in Robot
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaab02_1275969601
15:26:25.884 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaab02_1275969601 Rejected - No fragment in Robot
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276891205
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276891204
15:26:25.884 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276891203
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276891202
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276891202 Rejected - No fragment in Robot
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276804805
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276804805 Rejected - No fragment in Robot
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276804804
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276804804 Rejected - No fragment in Robot
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276804803
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276804803 Rejected - No fragment in Robot
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276804802
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276804802 Rejected - No fragment in Robot
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276718405
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276718405 Rejected - No fragment in Robot
15:26:25.887 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276718404
15:26:25.887 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276718404 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276718403
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276718403 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276718402
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276718402 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276632005
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276632005 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276632004
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276632004 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276632003
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276632003 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276632002
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276632002 Rejected - No fragment in Robot
15:26:25.890 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276545605
15:26:25.890 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276545605 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276545604
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276545604 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276545603
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276545603 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276545602
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276545602 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276459205
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276459205 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276459204
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276459204 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276459203
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276459203 Rejected - No fragment in Robot
15:26:25.893 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276459202
15:26:25.893 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276459202 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276372805
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276372805 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276372804
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276372804 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276372803
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276372803 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276372802
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276372802 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276286405
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276286405 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276286404
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276286404 Rejected - No fragment in Robot
15:26:25.896 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276286403
15:26:25.896 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276286403 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276286402
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276286402 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276200005
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276200005 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276200004
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276200004 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276200003
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276200003 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276200002
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276200002 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276116606
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276116606 Rejected - No fragment in Robot
15:26:25.899 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276116605
15:26:25.899 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276116605 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276116604
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276116604 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276116603
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276116603 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276027205
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276027205 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276027204
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276027204 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276027203
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276027203 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1276027202
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1276027202 Rejected - No fragment in Robot
15:26:25.902 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1275940805
15:26:25.902 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1275940805 Rejected - No fragment in Robot
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1275940804
15:26:25.906 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1275940804 Rejected - No fragment in Robot
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1275940803
15:26:25.906 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1275940803 Rejected - No fragment in Robot
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad01_1275940802
15:26:25.906 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad01_1275940802 Rejected - No fragment in Robot
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276898411
15:26:25.906 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276898411 Rejected - No fragment in Robot
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276898410
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276898409
15:26:25.906 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276898409 Rejected - No fragment in Robot
15:26:25.906 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276898408
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276812011
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276812011 Rejected - No fragment in Robot
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276812010
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276812010 Rejected - No fragment in Robot
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276812009
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276812009 Rejected - No fragment in Robot
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276812008
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276812008 Rejected - No fragment in Robot
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276725613
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276725613 Rejected - No fragment in Robot
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276725611
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276725611 Rejected - No fragment in Robot
15:26:25.909 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276725610
15:26:25.909 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276725610 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276725609
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276725609 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276639217
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276639217 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276639212
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276639212 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276639211
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276639211 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276639209
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276639209 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276552811
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276552811 Rejected - No fragment in Robot
15:26:25.912 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276552810
15:26:25.912 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276552810 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276552809
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276552809 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276552808
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276552808 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276466412
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276466412 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276466411
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276466411 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276466410
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276466410 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276466409
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276466409 Rejected - No fragment in Robot
15:26:25.915 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276380011
15:26:25.915 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276380011 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276380010
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276380010 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276380009
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276380009 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276380008
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276380008 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276293566
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276293566 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276293565
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276293565 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276293564
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276293564 Rejected - No fragment in Robot
15:26:25.918 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276293563
15:26:25.918 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276293563 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276207212
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276207212 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276207211
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276207211 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276207210
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276207210 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276207209
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276207209 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276207200
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276207200 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276123286
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276123286 Rejected - No fragment in Robot
15:26:25.921 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276123280
15:26:25.921 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276123280 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276123271
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276123271 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276123266
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276123266 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276122921
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276122921 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276122834
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276122834 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276120800
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276120800 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276034419
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276034419 Rejected - No fragment in Robot
15:26:25.924 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276034418
15:26:25.924 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276034418 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276034417
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276034417 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276034416
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276034416 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1276034402
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1276034402 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1275948015
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1275948015 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1275948014
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1275948014 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1275948013
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1275948013 Rejected - No fragment in Robot
15:26:25.927 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1275948012
15:26:25.927 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1275948012 Rejected - No fragment in Robot
15:26:25.930 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad02_1275948000
15:26:25.930 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad02_1275948000 Rejected - No fragment in Robot
15:26:25.930 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276905605
15:26:25.930 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276905605 Rejected - No fragment in Robot
15:26:25.930 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276905604
15:26:25.931 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276905603
15:26:25.931 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276905603 Rejected - No fragment in Robot
15:26:25.931 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276905602
15:26:25.931 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276819205
15:26:25.931 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276819204
15:26:25.933 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276819203
15:26:25.933 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276819202
15:26:25.933 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276819202 Rejected - No fragment in Robot
15:26:25.933 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276732842
15:26:25.933 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276732842 Rejected - No fragment in Robot
15:26:25.934 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276732837
15:26:25.934 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276732837 Rejected - No fragment in Robot
15:26:25.934 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276732833
15:26:25.934 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276732833 Rejected - No fragment in Robot
15:26:25.934 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276732826
15:26:25.934 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276732826 Rejected - No fragment in Robot
15:26:25.934 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276560005
15:26:25.934 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276560005 Rejected - No fragment in Robot
15:26:25.936 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276560004
15:26:25.936 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276560004 Rejected - No fragment in Robot
15:26:25.936 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276560003
15:26:25.936 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276560003 Rejected - No fragment in Robot
15:26:25.937 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276560002
15:26:25.937 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276560002 Rejected - No fragment in Robot
15:26:25.937 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276473605
15:26:25.937 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276473605 Rejected - No fragment in Robot
15:26:25.937 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276473604
15:26:25.937 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276473604 Rejected - No fragment in Robot
15:26:25.937 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276473603
15:26:25.937 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276473603 Rejected - No fragment in Robot
15:26:25.937 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276473602
15:26:25.937 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276473602 Rejected - No fragment in Robot
15:26:25.939 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276387205
15:26:25.939 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276387205 Rejected - No fragment in Robot
15:26:25.939 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276387204
15:26:25.939 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276387204 Rejected - No fragment in Robot
15:26:25.940 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276387203
15:26:25.940 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276387203 Rejected - No fragment in Robot
15:26:25.940 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276387202
15:26:25.940 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276387202 Rejected - No fragment in Robot
15:26:25.940 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276300805
15:26:25.940 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276300805 Rejected - No fragment in Robot
15:26:25.940 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276300804
15:26:25.940 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276300804 Rejected - No fragment in Robot
15:26:25.940 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276300803
15:26:25.940 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276300803 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276300802
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276300802 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276214408
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276214408 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276214407
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276214407 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276214406
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276214406 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276214405
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276214405 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276128005
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276128005 Rejected - No fragment in Robot
15:26:25.943 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276128004
15:26:25.943 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276128004 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276128003
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276128003 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276128002
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276128002 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276041605
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276041605 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276041604
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276041604 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276041603
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276041603 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1276041602
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1276041602 Rejected - No fragment in Robot
15:26:25.946 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1275955205
15:26:25.946 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1275955205 Rejected - No fragment in Robot
15:26:25.950 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1275955204
15:26:25.950 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1275955204 Rejected - No fragment in Robot
15:26:25.950 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1275955203
15:26:25.950 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1275955203 Rejected - No fragment in Robot
15:26:25.950 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad03_1275955202
15:26:25.950 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad03_1275955202 Rejected - No fragment in Robot
15:26:25.950 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276912805
15:26:25.951 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276912805 Rejected - No fragment in Robot
15:26:25.951 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276912804
15:26:25.951 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276912804 Rejected - No fragment in Robot
15:26:25.951 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276912803
15:26:25.951 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276912803 Rejected - No fragment in Robot
15:26:25.951 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276912802
15:26:25.953 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276826405
15:26:25.953 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276826404
15:26:25.953 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276826403
15:26:25.953 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276826403 Rejected - No fragment in Robot
15:26:25.953 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276826402
15:26:25.954 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276740005
15:26:25.954 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276740005 Rejected - No fragment in Robot
15:26:25.954 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276740004
15:26:25.954 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276740004 Rejected - No fragment in Robot
15:26:25.954 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276740003
15:26:25.954 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276740003 Rejected - No fragment in Robot
15:26:25.956 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276740002
15:26:25.956 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276740002 Rejected - No fragment in Robot
15:26:25.956 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276567205
15:26:25.956 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276567205 Rejected - No fragment in Robot
15:26:25.956 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276567204
15:26:25.956 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276567204 Rejected - No fragment in Robot
15:26:25.956 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276567203
15:26:25.956 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276567203 Rejected - No fragment in Robot
15:26:25.957 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276567202
15:26:25.957 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276567202 Rejected - No fragment in Robot
15:26:25.957 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276480805
15:26:25.957 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276480805 Rejected - No fragment in Robot
15:26:25.957 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276480804
15:26:25.957 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276480804 Rejected - No fragment in Robot
15:26:25.959 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276480803
15:26:25.959 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276480803 Rejected - No fragment in Robot
15:26:25.959 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276480802
15:26:25.959 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276480802 Rejected - No fragment in Robot
15:26:25.959 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276394405
15:26:25.959 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276394405 Rejected - No fragment in Robot
15:26:25.960 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276394404
15:26:25.960 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276394404 Rejected - No fragment in Robot
15:26:25.960 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276394403
15:26:25.960 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276394403 Rejected - No fragment in Robot
15:26:25.960 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276394402
15:26:25.960 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276394402 Rejected - No fragment in Robot
15:26:25.960 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276308005
15:26:25.960 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276308005 Rejected - No fragment in Robot
15:26:25.962 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276308004
15:26:25.962 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276308004 Rejected - No fragment in Robot
15:26:25.962 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276308003
15:26:25.962 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276308003 Rejected - No fragment in Robot
15:26:25.962 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276308002
15:26:25.962 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276308002 Rejected - No fragment in Robot
15:26:25.963 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276221605
15:26:25.963 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276221605 Rejected - No fragment in Robot
15:26:25.963 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276221604
15:26:25.963 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276221604 Rejected - No fragment in Robot
15:26:25.963 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276221603
15:26:25.963 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276221603 Rejected - No fragment in Robot
15:26:25.963 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276221602
15:26:25.963 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276221602 Rejected - No fragment in Robot
15:26:25.965 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276135205
15:26:25.965 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276135205 Rejected - No fragment in Robot
15:26:25.965 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276135204
15:26:25.965 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276135204 Rejected - No fragment in Robot
15:26:25.965 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276135203
15:26:25.965 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276135203 Rejected - No fragment in Robot
15:26:25.966 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276135202
15:26:25.966 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276135202 Rejected - No fragment in Robot
15:26:25.966 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276048805
15:26:25.966 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276048805 Rejected - No fragment in Robot
15:26:25.966 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276048804
15:26:25.966 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276048804 Rejected - No fragment in Robot
15:26:25.966 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276048803
15:26:25.966 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276048803 Rejected - No fragment in Robot
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1276048802
15:26:25.969 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1276048802 Rejected - No fragment in Robot
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1275962405
15:26:25.969 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1275962405 Rejected - No fragment in Robot
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1275962404
15:26:25.969 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1275962404 Rejected - No fragment in Robot
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1275962403
15:26:25.969 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1275962403 Rejected - No fragment in Robot
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaad04_1275962402
15:26:25.969 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaad04_1275962402 Rejected - No fragment in Robot
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276898404
15:26:25.969 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276812000
15:26:25.969 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276812000 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276725602
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276725602 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276639202
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276639202 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276552801
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276552801 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276466403
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276466403 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276380003
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276380003 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276294593
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276294593 Rejected - No fragment in Robot
15:26:25.972 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276207201
15:26:25.972 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276207201 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276120801
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276120801 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1276034403
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1276034403 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan02_1275948003
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan02_1275948003 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan05_1276563600
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan05_1276563600 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan05_1275958800
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan05_1275958800 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaan09_1276728953
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaan09_1276728953 Rejected - No fragment in Robot
15:26:25.976 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276916415
15:26:25.976 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276916415 Rejected - No fragment in Robot
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276916414
15:26:25.979 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276916414 Rejected - No fragment in Robot
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276916406
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276916405
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276916404
15:26:25.979 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276916404 Rejected - No fragment in Robot
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276830013
15:26:25.979 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276830013 Rejected - No fragment in Robot
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276830012
15:26:25.979 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276830012 Rejected - No fragment in Robot
15:26:25.979 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276830006
15:26:25.979 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276830006 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276830005
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276830005 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276830004
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276830004 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276745014
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276745014 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276743617
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276743617 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276743612
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276743612 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276743605
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276743605 Rejected - No fragment in Robot
15:26:25.982 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276743604
15:26:25.982 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276743604 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276657208
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276657208 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276657207
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276657207 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276657206
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276657206 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276657205
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276657205 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276657204
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276657204 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276570814
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276570814 Rejected - No fragment in Robot
15:26:25.985 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276570813
15:26:25.985 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276570813 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276570806
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276570806 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276570805
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276570805 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276570804
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276570804 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276484411
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276484411 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276484410
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276484410 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276484409
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276484409 Rejected - No fragment in Robot
15:26:25.988 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276484408
15:26:25.988 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276484408 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276484406
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276484406 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276398008
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276398008 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276398007
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276398007 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276398006
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276398006 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276398005
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276398005 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276398004
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276398004 Rejected - No fragment in Robot
15:26:25.991 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276312771
15:26:25.991 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276312771 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276311619
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276311619 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276311612
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276311612 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276311605
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276311605 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276311604
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276311604 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276225214
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276225214 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276225212
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276225212 Rejected - No fragment in Robot
15:26:25.994 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276225206
15:26:25.994 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276225206 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276225205
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276225205 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276225204
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276225204 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276138813
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276138813 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276138807
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276138807 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276138806
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276138806 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276138805
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276138805 Rejected - No fragment in Robot
15:26:25.997 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaasp1-bkp_1276138804
15:26:25.997 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaasp1-bkp_1276138804 Rejected - No fragment in Robot
15:26:26.000 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276898405
15:26:26.000 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276812001
15:26:26.000 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276812001 Rejected - No fragment in Robot
15:26:26.000 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276725603
15:26:26.001 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276725603 Rejected - No fragment in Robot
15:26:26.001 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276639203
15:26:26.001 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276639203 Rejected - No fragment in Robot
15:26:26.001 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276552802
15:26:26.001 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276552802 Rejected - No fragment in Robot
15:26:26.001 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276466404
15:26:26.001 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276466404 Rejected - No fragment in Robot
15:26:26.001 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276380004
15:26:26.001 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276380004 Rejected - No fragment in Robot
15:26:26.004 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276293603
15:26:26.004 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276293603 Rejected - No fragment in Robot
15:26:26.004 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276207202
15:26:26.004 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276207202 Rejected - No fragment in Robot
15:26:26.004 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276120802
15:26:26.005 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276120802 Rejected - No fragment in Robot
15:26:26.005 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1276034404
15:26:26.005 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1276034404 Rejected - No fragment in Robot
15:26:26.005 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaaw01_1275948004
15:26:26.005 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaaw01_1275948004 Rejected - No fragment in Robot
15:26:26.005 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276919381
15:26:26.005 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276919381 Rejected - No fragment in Robot
15:26:26.005 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276919292
15:26:26.005 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276919292 Rejected - No fragment in Robot
15:26:26.007 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276919074
15:26:26.007 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276919074 Rejected - No fragment in Robot
15:26:26.007 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276919066
15:26:26.007 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276919066 Rejected - No fragment in Robot
15:26:26.008 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276918333
15:26:26.008 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276918333 Rejected - No fragment in Robot
15:26:26.008 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276917881
15:26:26.008 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276917874
15:26:26.008 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276917866
15:26:26.008 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276916410
15:26:26.010 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276916408
15:26:26.011 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276832945
15:26:26.011 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276832945 Rejected - No fragment in Robot
15:26:26.011 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276832824
15:26:26.011 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276832824 Rejected - No fragment in Robot
15:26:26.011 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276832626
15:26:26.011 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276832626 Rejected - No fragment in Robot
15:26:26.011 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276832619
15:26:26.011 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276832619 Rejected - No fragment in Robot
15:26:26.011 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276831925
15:26:26.011 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276831925 Rejected - No fragment in Robot
15:26:26.011 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276831436
15:26:26.011 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276831436 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276831428
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276831428 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276831420
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276831420 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276830010
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276830010 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276830008
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276830008 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276747368
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276747368 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276746583
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276746583 Rejected - No fragment in Robot
15:26:26.015 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276746378
15:26:26.015 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276746378 Rejected - No fragment in Robot
15:26:26.018 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276746372
15:26:26.018 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276746372 Rejected - No fragment in Robot
15:26:26.018 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276745534
15:26:26.018 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276745534 Rejected - No fragment in Robot
15:26:26.018 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276745070
15:26:26.018 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276745070 Rejected - No fragment in Robot
15:26:26.018 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276745063
15:26:26.018 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276745063 Rejected - No fragment in Robot
15:26:26.018 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276745054
15:26:26.018 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276745054 Rejected - No fragment in Robot
15:26:26.018 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276743609
15:26:26.018 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276743609 Rejected - No fragment in Robot
15:26:26.019 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276743607
15:26:26.019 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276743607 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657220
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657220 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657219
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657219 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657218
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657218 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657217
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657217 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657216
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657216 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657215
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657215 Rejected - No fragment in Robot
15:26:26.022 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657214
15:26:26.022 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657214 Rejected - No fragment in Robot
15:26:26.026 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657213
15:26:26.026 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657213 Rejected - No fragment in Robot
15:26:26.026 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657212
15:26:26.026 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657212 Rejected - No fragment in Robot
15:26:26.026 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276657210
15:26:26.026 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276657210 Rejected - No fragment in Robot
15:26:26.026 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276574367
15:26:26.026 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276574367 Rejected - No fragment in Robot
15:26:26.026 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276574107
15:26:26.026 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276574107 Rejected - No fragment in Robot
15:26:26.027 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276573839
15:26:26.027 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276573839 Rejected - No fragment in Robot
15:26:26.027 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276573829
15:26:26.027 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276573829 Rejected - No fragment in Robot
15:26:26.030 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276572749
15:26:26.030 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276572749 Rejected - No fragment in Robot
15:26:26.030 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276572290
15:26:26.030 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276572290 Rejected - No fragment in Robot
15:26:26.030 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276572285
15:26:26.031 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276572285 Rejected - No fragment in Robot
15:26:26.031 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276572277
15:26:26.031 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276572277 Rejected - No fragment in Robot
15:26:26.031 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276570810
15:26:26.031 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276570810 Rejected - No fragment in Robot
15:26:26.031 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276570808
15:26:26.031 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276570808 Rejected - No fragment in Robot
15:26:26.031 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484423
15:26:26.031 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484423 Rejected - No fragment in Robot
15:26:26.033 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484422
15:26:26.033 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484422 Rejected - No fragment in Robot
15:26:26.033 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484421
15:26:26.033 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484421 Rejected - No fragment in Robot
15:26:26.034 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484417
15:26:26.034 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484417 Rejected - No fragment in Robot
15:26:26.034 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484416
15:26:26.034 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484416 Rejected - No fragment in Robot
15:26:26.034 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484415
15:26:26.034 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484415 Rejected - No fragment in Robot
15:26:26.034 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484414
15:26:26.034 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484414 Rejected - No fragment in Robot
15:26:26.034 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484413
15:26:26.034 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484413 Rejected - No fragment in Robot
15:26:26.036 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484412
15:26:26.036 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484412 Rejected - No fragment in Robot
15:26:26.037 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276484404
15:26:26.037 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276484404 Rejected - No fragment in Robot
15:26:26.037 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398028
15:26:26.037 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398028 Rejected - No fragment in Robot
15:26:26.037 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398027
15:26:26.037 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398027 Rejected - No fragment in Robot
15:26:26.037 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398023
15:26:26.037 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398023 Rejected - No fragment in Robot
15:26:26.037 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398017
15:26:26.037 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398017 Rejected - No fragment in Robot
15:26:26.037 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398016
15:26:26.037 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398016 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398015
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398015 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398014
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398014 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398013
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398013 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398012
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398012 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276398010
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276398010 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276315645
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276315645 Rejected - No fragment in Robot
15:26:26.040 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313845
15:26:26.040 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313845 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313617
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313617 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313609
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313609 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313528
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313528 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313060
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313060 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313051
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313051 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276313043
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276313043 Rejected - No fragment in Robot
15:26:26.043 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276311609
15:26:26.043 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276311609 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276311607
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276311607 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276228876
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276228876 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276228534
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276228534 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276228280
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276228280 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276228271
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276228271 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276227143
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276227143 Rejected - No fragment in Robot
15:26:26.046 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276226658
15:26:26.046 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276226658 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276226648
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276226648 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276226635
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276226635 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276225210
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276225210 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276225208
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276225208 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276142331
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276142331 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276140814
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276140814 Rejected - No fragment in Robot
15:26:26.049 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276140579
15:26:26.049 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276140579 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276140571
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276140571 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276139442
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276139442 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276138829
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276138829 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276138822
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276138822 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276138814
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276138814 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276138811
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276138811 Rejected - No fragment in Robot
15:26:26.052 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadbp1-bkp_1276138809
15:26:26.052 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadbp1-bkp_1276138809 Rejected - No fragment in Robot
15:26:26.055 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276898401
15:26:26.055 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276898401 Rejected - No fragment in Robot
15:26:26.055 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276812002
15:26:26.055 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276812002 Rejected - No fragment in Robot
15:26:26.055 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276725604
15:26:26.055 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276725604 Rejected - No fragment in Robot
15:26:26.055 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276639204
15:26:26.055 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276639204 Rejected - No fragment in Robot
15:26:26.055 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276552803
15:26:26.055 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276552803 Rejected - No fragment in Robot
15:26:26.055 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276466405
15:26:26.055 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276466405 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276380005
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276380005 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276293602
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276293602 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276207203
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276207203 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276120803
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276120803 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1276034405
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1276034405 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadc01-bkp_1275948005
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadc01-bkp_1275948005 Rejected - No fragment in Robot
15:26:26.058 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276941600
15:26:26.058 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276941600 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276855200
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276855200 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276768800
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276768800 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276682400
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276682400 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276596000
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276596000 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276509600
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276509600 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276423200
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276423200 Rejected - No fragment in Robot
15:26:26.061 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276336800
15:26:26.061 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276336800 Rejected - No fragment in Robot
15:26:26.064 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276250400
15:26:26.064 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276250400 Rejected - No fragment in Robot
15:26:26.064 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276164000
15:26:26.064 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276164000 Rejected - No fragment in Robot
15:26:26.064 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1276077600
15:26:26.064 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1276077600 Rejected - No fragment in Robot
15:26:26.064 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadf01_1275991200
15:26:26.064 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadf01_1275991200 Rejected - No fragment in Robot
15:26:26.064 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276934400
15:26:26.064 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276934400 Rejected - No fragment in Robot
15:26:26.065 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276848000
15:26:26.065 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276848000 Rejected - No fragment in Robot
15:26:26.065 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276761600
15:26:26.065 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276761600 Rejected - No fragment in Robot
15:26:26.067 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276675200
15:26:26.067 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276675200 Rejected - No fragment in Robot
15:26:26.067 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276588800
15:26:26.067 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276588800 Rejected - No fragment in Robot
15:26:26.067 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276502400
15:26:26.067 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276502400 Rejected - No fragment in Robot
15:26:26.067 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276416004
15:26:26.067 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276416004 Rejected - No fragment in Robot
15:26:26.068 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276329607
15:26:26.068 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276329607 Rejected - No fragment in Robot
15:26:26.068 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276243200
15:26:26.068 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276243200 Rejected - No fragment in Robot
15:26:26.068 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276156800
15:26:26.068 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276156800 Rejected - No fragment in Robot
15:26:26.071 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1276070400
15:26:26.071 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1276070400 Rejected - No fragment in Robot
15:26:26.071 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnadi02_1275984000
15:26:26.071 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnadi02_1275984000 Rejected - No fragment in Robot
15:26:26.071 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276900679
15:26:26.071 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276900679 Rejected - No fragment in Robot
15:26:26.071 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276899509
15:26:26.071 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276899509 Rejected - No fragment in Robot
15:26:26.071 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276898400
15:26:26.071 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276815639
15:26:26.071 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276815639 Rejected - No fragment in Robot
15:26:26.074 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276815184
15:26:26.074 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276815184 Rejected - No fragment in Robot
15:26:26.075 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276812003
15:26:26.075 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276812003 Rejected - No fragment in Robot
15:26:26.075 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276729848
15:26:26.075 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276729848 Rejected - No fragment in Robot
15:26:26.075 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276729110
15:26:26.075 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276729110 Rejected - No fragment in Robot
15:26:26.075 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276725600
15:26:26.075 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276725600 Rejected - No fragment in Robot
15:26:26.075 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276642427
15:26:26.075 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276642427 Rejected - No fragment in Robot
15:26:26.078 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276642055
15:26:26.078 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276642055 Rejected - No fragment in Robot
15:26:26.078 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276639201
15:26:26.078 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276639201 Rejected - No fragment in Robot
15:26:26.078 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276556313
15:26:26.078 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276556313 Rejected - No fragment in Robot
15:26:26.078 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276555911
15:26:26.078 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276555911 Rejected - No fragment in Robot
15:26:26.078 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276552800
15:26:26.078 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276552800 Rejected - No fragment in Robot
15:26:26.078 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276475052
15:26:26.078 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276475052 Rejected - No fragment in Robot
15:26:26.082 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276469536
15:26:26.082 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276469536 Rejected - No fragment in Robot
15:26:26.082 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276466400
15:26:26.082 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276466400 Rejected - No fragment in Robot
15:26:26.082 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276388529
15:26:26.082 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276388529 Rejected - No fragment in Robot
15:26:26.082 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276388449
15:26:26.082 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276388449 Rejected - No fragment in Robot
15:26:26.082 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276380000
15:26:26.082 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276380000 Rejected - No fragment in Robot
15:26:26.082 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276328266
15:26:26.082 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276328266 Rejected - No fragment in Robot
15:26:26.085 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276328186
15:26:26.085 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276328186 Rejected - No fragment in Robot
15:26:26.085 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276293604
15:26:26.085 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276293604 Rejected - No fragment in Robot
15:26:26.085 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276210921
15:26:26.085 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276210921 Rejected - No fragment in Robot
15:26:26.085 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276210628
15:26:26.085 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276210628 Rejected - No fragment in Robot
15:26:26.085 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276207204
15:26:26.085 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276207204 Rejected - No fragment in Robot
15:26:26.086 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276123869
15:26:26.086 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276123869 Rejected - No fragment in Robot
15:26:26.089 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276123119
15:26:26.089 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276123119 Rejected - No fragment in Robot
15:26:26.089 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276120805
15:26:26.089 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276120805 Rejected - No fragment in Robot
15:26:26.089 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276034688
15:26:26.089 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276034688 Rejected - No fragment in Robot
15:26:26.089 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1276034486
15:26:26.089 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1276034486 Rejected - No fragment in Robot
15:26:26.089 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1275949316
15:26:26.089 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1275949316 Rejected - No fragment in Robot
15:26:26.089 [9109] <2> vltrun@PassImageThruFilters^1293: IMG_ID=tmnaim01_1275948432
15:26:26.089 [9109] <8> vltrun@ImgFilterVaulted::DoValidate^1293: Image: tmnaim01_1275948432 Rejected - No fragment in Robot
15:26:26.089 [9109] <4> vltrun@ImageInterface::next_image^1293: Collected 503 IMGs
15:26:26.090 [9109] <2> vltrun@SaveImages^1293: IMGs=24 SDIR=/usr/openv/netbackup/vault/sessions/tmna_vault/sid1293 IN=preview.list
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.090 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 2
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148386
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=3 to JOBD
15:26:26.091 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:26 (1277148386)
Elapsed   :0:1
15:26:26.091 [9109] <4> vltrun@VltSession::runSession^1293: skipping duplicate_bymid step
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148386
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=59 to JOBD
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277148386 sent TRY_MSG: VAULT_DPLCTN_SKIPPED 1277148386

15:26:26.091 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:26.091 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = suspend_media
StartTime  = 2010.06.21 15:26:26 (1277148386)
15:26:26.091 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: suspend_media
15:26:26.091 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=suspend_media
15:26:26.091 [9109] <8> vltrun@DoSuspend^1293: Skipping suspend, function not enabled in Params
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148386
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=60 to JOBD
15:26:26.091 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:26 (1277148386)
Elapsed   :0:0
15:26:26.091 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:26.091 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = vlt_ejectlist_notify
StartTime  = 2010.06.21 15:26:26 (1277148386)
15:26:26.091 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: vlt_ejectlist_notify
15:26:26.091 [9109] <2> vltrun@VOpExecuteScript::doOperation^1293: CALLING /usr/openv/netbackup/bin/vlt_ejectlist_notify script; robot:0, vault:tmna_vault, profile:daily_profile, and sid:1293
15:26:26.091 [9109] <2> vltrun@VOpExecuteScript::doOperation^1293: Vault Notify Script=/usr/openv/netbackup/bin/vlt_ejectlist_notify RV=0
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148386
15:26:26.091 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=61 to JOBD
15:26:26.091 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:26 (1277148386)
Elapsed   :0:0
15:26:26.091 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:26.091 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = collect_eject_media
StartTime  = 2010.06.21 15:26:26 (1277148386)
15:26:26.091 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: collect_eject_media
15:26:26.091 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=collect_eject_media
15:26:26.091 [9109] <2> vltrun@cVolInvMgr::LoadInvByPool^1293: Host=tmnaim01 Npools=3
15:26:26.213 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: V_QUERY_BYPOOL H=tmnaim01 Pool=2wk-offsite
15:26:26.227 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: V_QUERY_BYPOOL H=tmnaim01 Pool=3wk-offsite
15:26:26.254 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: V_QUERY_BYPOOL H=tmnaim01 Pool=catalog-offsite
15:26:26.270 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: Loaded 85 media records into volInvList
15:26:26.270 [9109] <8> vltrun@AddSelectedImagesEjectMedia^1293: MDA=000153 selected but not in RBT_GRP=000_00000_TLD, not ejecting
15:26:26.270 [9109] <8> vltrun@AddSelectedImagesEjectMedia^1293: MDA=000161 selected but not in RBT_GRP=000_00000_TLD, not ejecting
15:26:26.270 [9109] <8> vltrun@AddSelectedImagesEjectMedia^1293: MDA=000163 selected but not in RBT_GRP=000_00000_TLD, not ejecting
15:26:26.270 [9109] <4> vltrun@AddSelectedImagesEjectMedia^1293: MDA=000178 HOST=tmnaim01 to be added to eject.list
15:26:26.270 [9109] <4> vltrun@AddSelectedImagesEjectMedia^1293: MDA=000182 HOST=tmnaim01 to be added to eject.list
15:26:26.270 [9109] <4> vltrun@AddAddonEjectMedia^1293: No addon_medialist file found
15:26:26.270 [9109] <4> vltrun@AddDLMEjectMedia^1293: No DLM pools specified for Eject
15:26:26.271 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148386
15:26:26.271 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=62 to JOBD
15:26:26.271 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:26 (1277148386)
Elapsed   :0:0
15:26:26.271 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:26.271 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = assignslot_media
StartTime  = 2010.06.21 15:26:26 (1277148386)
15:26:26.271 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: assignslot_media
15:26:26.271 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=assignslot_media
15:26:26.271 [9109] <2> vltrun@assign_slot_lock^1293: R=TLD(0) V=tmna_vault
15:26:26.271 [9109] <2> vltrun@VaultLockProxy::acquire_lock^1293: Type=VAULT_ASSIGN_SLOT.LOCK Key=_TLD(0)_tmna_vault Limit=1
15:26:26.271 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277148386 sent TRY_MSG: VAULT_WAITING_FOR_ASSIGN_SLOT_LOCK 1277148386

15:26:28.280 [9109] <4> vltrun@VaultLockProxy::acquire_lock^1293: Lock acquired key=tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
15:26:28.280 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277148388 sent TRY_MSG: VAULT_ASSIGN_SLOT_LOCK_ACQUIRED 1277148388

15:26:28.280 [9109] <2> vltrun@assign_slot_lock^1293: assign_slot lock=_TLD(0)_tmna_vault acquired
15:26:28.280 [9109] <2> vltrun@DoAssignSlot^1293: Assiging Slots for Media in Vault=tmna_vault CntrVault?=0
15:26:28.280 [9109] <2> vltrun@LoadOffsiteSlotInfo^1293: V=tmna_vault
15:26:28.330 [9109] <2> vltrun@DoAssignSlot^1293: Attempt to assign slot to MID=000178
15:26:28.330 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000178
15:26:28.336 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000178 #Media=1
15:26:28.336 [9109] <4> vltrun@DoAssignSlot^1293: Slot Assignment not required: MDA=000178 V=tmna_vault SLOT=110
15:26:28.336 [9109] <2> vltrun@UpdateOffsiteSessionId^1293: MID=000178 SID=1293
15:26:28.385 [9109] <2> vltrun@DoAssignSlot^1293: Attempt to assign slot to MID=000182
15:26:28.385 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000182
15:26:28.391 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000182 #Media=2
15:26:28.391 [9109] <4> vltrun@DoAssignSlot^1293: Slot Assignment not required: MDA=000182 V=tmna_vault SLOT=134
15:26:28.391 [9109] <2> vltrun@UpdateOffsiteSessionId^1293: MID=000182 SID=1293
15:26:28.399 [9109] <2> vltrun@VaultLock::release^1293: LT=VAULT_ASSIGN_SLOT.LOCK
15:26:28.399 [9109] <2> vltrun@VaultLockProxy::release_lock^1293: Type=VAULT_ASSIGN_SLOT.LOCK
15:26:28.402 [9109] <4> vltrun@VaultLockProxy::release_lock^1293: released lock. Key=tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
15:26:28.402 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277148388 sent TRY_MSG: VAULT_ASSIGN_SLOT_LOCK_RELEASED 1277148388

15:26:28.402 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148388
15:26:28.402 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=63 to JOBD
15:26:28.402 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 15:26:28 (1277148388)
Elapsed   :0:2
15:26:28.402 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
15:26:28.402 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = catalog_backup
StartTime  = 2010.06.21 15:26:28 (1277148388)
15:26:28.402 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: catalog_backup
15:26:28.402 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277148388
15:26:28.402 [9109] <4> vltrun@VaultJobMonitor::_send_operation^1293: Sent Operation=7 op to Job daemon
15:26:28.402 [9109] <2> vltrun@VaultJobMonitor::_find_op_string^1293: Found 7->CATALOG_BACKUP
15:26:28.402 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277148388 sent TRY_MSG: BEGIN_OPERATION 1277148388 CATALOG_BACKUP

15:26:28.692 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277148388 sent TRY_MSG: VAULT_CTLG_BKUP_START 1277148388 NBU-Catalog-bkup vault-catalog

15:26:28.692 [9109] <2> vltrun@DoCatalogBu^1293: Catalog Backup job starts with policy "NBU-Catalog-bkup" and schedule "vault-catalog"...
16:17:22.337 [9109] <2> vltrun@DoCatalogBu^1293: Catalog Backup job ends with status = 0
16:17:22.355 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151442 sent TRY_MSG: VAULT_CTLG_BKUP_END 1277151442

16:17:22.355 [9109] <2> vltrun@DoCatalogBu^1293: Catalog Backup job completed successfully!
16:17:22.355 [9109] <2> vltrun@DoCatalogBu^1293: Collecting catalog images created by this catalog backup job
16:17:22.355 [9109] <2> vltrun@FilterImages^1293: PARENT_IMG_ID=NBU-Catalog-bkup_1277148392_UBAK
16:17:22.369 [9109] <2> vltrun@ImageInterface::init_query^1293: CLNT=tmnaim01 PARENT_IMG_ID=NBU-Catalog-bkup_1277148392_UBAK
16:17:22.668 [9109] <4> vltrun@ImageInterface::next_image^1293: Collected 2 IMGs
16:17:22.668 [9109] <2> vltrun@DoCatalogBu^1293: Appending catalog images to preview file
16:17:22.668 [9109] <2> vltrun@appendCatalogImages^1293: IN=preview.list
16:17:22.678 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 1
16:17:22.683 [9109] <2> vltrun@NBUVImage::find_source_copy^1293: Set source copy to 1
16:17:22.683 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000284
16:17:22.691 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000284 #Media=1
16:17:22.691 [9109] <4> vltrun@CollectCatalogBackupEjectMedia^1293: CtlgBkup: MDA=000284 HOST=tmnaim01 to be added to eject.list
16:17:22.691 [9109] <4> vltrun@DoCatalogBu^1293: Adding 1 Ctlg Bkup media for eject
16:17:22.691 [9109] <4> vltrun@SuspendEjectingMedia^1293: Suspend is NOT set as immediately, not suspending now...
16:17:22.691 [9109] <2> vltrun@DoCatalogBu^1293: Catalog Backup step succeeded!
16:17:22.695 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277151442
16:17:22.695 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=72 to JOBD
16:17:22.696 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151442 sent TRY_MSG: END_OPERATION 1277151442

16:17:22.696 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 16:17:22 (1277151442)
Elapsed   :50:54
16:17:22.696 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
16:17:22.696 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = assignslot_media
StartTime  = 2010.06.21 16:17:22 (1277151442)
16:17:22.696 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: assignslot_media
16:17:22.696 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=assignslot_media
16:17:22.696 [9109] <2> vltrun@assign_slot_lock^1293: R=TLD(0) V=tmna_vault
16:17:22.696 [9109] <2> vltrun@VaultLockProxy::acquire_lock^1293: Type=VAULT_ASSIGN_SLOT.LOCK Key=_TLD(0)_tmna_vault Limit=1
16:17:22.696 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151442 sent TRY_MSG: VAULT_WAITING_FOR_ASSIGN_SLOT_LOCK 1277151442

16:17:24.701 [9109] <4> vltrun@VaultLockProxy::acquire_lock^1293: Lock acquired key=tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
16:17:24.701 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151444 sent TRY_MSG: VAULT_ASSIGN_SLOT_LOCK_ACQUIRED 1277151444

16:17:24.701 [9109] <2> vltrun@assign_slot_lock^1293: assign_slot lock=_TLD(0)_tmna_vault acquired
16:17:24.701 [9109] <2> vltrun@DoAssignSlot^1293: Assiging Slots for Media in Vault=tmna_vault CntrVault?=0
16:17:24.701 [9109] <2> vltrun@LoadOffsiteSlotInfo^1293: V=tmna_vault
16:17:24.758 [9109] <2> vltrun@DoAssignSlot^1293: Attempt to assign slot to MID=000178
16:17:24.758 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000178
16:17:24.765 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000178 #Media=1
16:17:24.765 [9109] <4> vltrun@DoAssignSlot^1293: Slot Assignment not required: MDA=000178 V=tmna_vault SLOT=110
16:17:24.765 [9109] <2> vltrun@DoAssignSlot^1293: Attempt to assign slot to MID=000182
16:17:24.765 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000182
16:17:24.771 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000182 #Media=2
16:17:24.771 [9109] <4> vltrun@DoAssignSlot^1293: Slot Assignment not required: MDA=000182 V=tmna_vault SLOT=134
16:17:24.771 [9109] <2> vltrun@DoAssignSlot^1293: Attempt to assign slot to MID=000284
16:17:24.771 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000284
16:17:24.777 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000284 #Media=3
16:17:24.777 [9109] <2> vltrun@getNextSlot^1293: CurrSlot=1
16:17:24.777 [9109] <2> vltrun@getNextSlot^1293: NextSlot=140 Total=200
16:17:24.777 [9109] <4> vltrun@DoAssignSlot^1293: Old Media Info: MDA=000284 OldV=- OldSID=0 OldSlot=0
16:17:24.777 [9109] <4> vltrun@DoAssignSlot^1293: New Media Info: MDA=000284 NewV=tmna_vault NewSID=1293 NewSlot=140
16:17:24.777 [9109] <2> vltrun@modifyMediaSlotField^1293: Assigning Slot: MDA=000284 V=tmna_vault SLOT=140
16:17:24.777 [9109] <2> vltrun@UpdateOffsiteVault^1293: MID=000284 V=tmna_vault
16:17:24.853 [9109] <2> vltrun@UpdateOffsiteSlot^1293: MID=000284 SLOT=140
16:17:24.861 [9109] <2> vltrun@UpdateOffsiteSessionId^1293: MID=000284 SID=1293
16:17:24.869 [9109] <2> vltrun@VaultLock::release^1293: LT=VAULT_ASSIGN_SLOT.LOCK
16:17:24.869 [9109] <2> vltrun@VaultLockProxy::release_lock^1293: Type=VAULT_ASSIGN_SLOT.LOCK
16:17:24.872 [9109] <4> vltrun@VaultLockProxy::release_lock^1293: released lock. Key=tmnaim01.VAULT_ASSIGN_SLOT.LOCK_TLD(0)_tmna_vault
16:17:24.872 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151444 sent TRY_MSG: VAULT_ASSIGN_SLOT_LOCK_RELEASED 1277151444

16:17:24.872 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277151444
16:17:24.872 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=73 to JOBD
16:17:24.872 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 16:17:24 (1277151444)
Elapsed   :0:2
16:17:24.872 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
16:17:24.872 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = genejectlist
StartTime  = 2010.06.21 16:17:24 (1277151444)
16:17:24.872 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: genejectlist
16:17:24.872 [9109] <2> vltrun@GenMediaEjectList^1293: entering function
16:17:24.889 [9109] <2> vltrun@connectToVRTObject^1293: Connecting to vccs server tmnaim01
16:17:25.003 [9109] <2> vltrun@connectToVRTObject^1293: VaultRuntimePortal init succeeded
16:17:25.042 [9109] <4> vltrun@GenMediaEjectList^1293: Appending session to vlteject.mstr returned=0
16:17:25.042 [9109] <2> vltrun@GenMediaEjectList^1293: Suspend Mode = LATER
16:17:25.042 [9109] <2> vltrun@GenMediaEjectList^1293: ADDed media=000178 SUSPEND host=tmnaim01 for Eject
16:17:25.042 [9109] <2> vltrun@GenMediaEjectList^1293: ADDed media=000182 SUSPEND host=tmnaim01 for Eject
16:17:25.042 [9109] <2> vltrun@GenMediaEjectList^1293: ADDed media=000284 SUSPEND host=tmnaim01 for Eject
16:17:25.042 [9109] <4> vltrun@GenMediaEjectList^1293: Generated eject list containing 3 media Id(s)
16:17:25.044 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277151445
16:17:25.044 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=74 to JOBD
16:17:25.044 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 16:17:25 (1277151445)
Elapsed   :0:1
16:17:25.044 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
16:17:25.044 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = recall_media
StartTime  = 2010.06.21 16:17:25 (1277151445)
16:17:25.044 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: recall_media
16:17:25.044 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=recall_media
16:17:25.044 [9109] <2> vltrun@cVolInvMgr::LoadInvByPool^1293: Host=tmnaim01 Npools=3
16:17:25.067 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: V_QUERY_BYPOOL H=tmnaim01 Pool=2wk-offsite
16:17:25.092 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: V_QUERY_BYPOOL H=tmnaim01 Pool=3wk-offsite
16:17:25.113 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: V_QUERY_BYPOOL H=tmnaim01 Pool=catalog-offsite
16:17:25.129 [9109] <4> vltrun@cVolInvMgr::LoadInvByPool^1293: Loaded 86 media records into volInvList
16:17:25.129 [9109] <2> vltrun@cVolInvMgr::LoadInvScratchPools^1293: H=tmnaim01
16:17:25.131 [9109] <4> vltrun@cVolInvMgr::LoadInvScratchPools^1293: V_QUERY_BYPOOL P=Scratch PN=4
16:17:25.165 [9109] <4> vltrun@cVolInvMgr::LoadInvScratchPools^1293: Loaded 139 media from 1 scratch pools SC=0
16:17:25.166 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277151445
16:17:25.166 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=75 to JOBD
16:17:25.167 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 16:17:25 (1277151445)
Elapsed   :0:0
16:17:25.167 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
16:17:25.167 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = gen_session_debug_files
StartTime  = 2010.06.21 16:17:25 (1277151445)
16:17:25.167 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: gen_session_debug_files
16:17:25.167 [9109] <2> vltrun@VOpMinor::doOperation^1293: OP=gen_session_debug_files
16:17:25.167 [9109] <4> vltrun@CreateDebugFiles^1293: Debug touch file not found, Skipping debug file(s) creation
16:17:25.167 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277151445
16:17:25.167 [9109] <2> vltrun@VaultJobMonitor::IncrementJobProgress^1293: SENT completion pct=76 to JOBD
16:17:25.167 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP COMPLETE
End Time  :2010.06.21 16:17:25 (1277151445)
Elapsed   :0:0
16:17:25.167 [9109] <2> vltrun@VltSession::sessionStep()^1293: entering function
16:17:25.167 [9109] <8> vltrun@VltSession::sessionStep()^1293: Session STEP Information
RVP   = daily_profile
SID   = 1293
STEP   = eject_media
StartTime  = 2010.06.21 16:17:25 (1277151445)
16:17:25.167 [9109] <2> vltrun@VltSession::lock_and_operate^1293: OP: eject_media
16:17:25.167 [9109] <4> vltrun@v_nb_conf_media_unmount_delay^1293: Media Unmount Delay=180
16:17:25.167 [9109] <2> vltrun@connectToVRTObject^1293: Connecting to vccs server tmnaim01
16:17:25.171 [9109] <2> vltrun@connectToVRTObject^1293: VaultRuntimePortal init succeeded
16:17:25.254 [9109] <2> vltrun@getAllSessions^1293: Returning 3 number of records
16:17:25.260 [9109] <2> vltrun@veject()^1293: Received call to veject with config parms
16:17:25.260 [9109] <4> vltrun@veject()^1293: *************************VEJECT STARTS**********************
16:17:25.260 [9109] <2> vltrun@veject()^1293: Received eject request with following parms
16:17:25.260 [9109] <2> vltrun@veject()^1293: Robot Name = 0
16:17:25.260 [9109] <2> vltrun@veject()^1293: Vault Name = tmna_vault
16:17:25.260 [9109] <2> vltrun@veject()^1293: Profile Name = daily_profile
16:17:25.260 [9109] <2> vltrun@veject()^1293: Session Id= 1293
16:17:25.260 [9109] <2> vltrun@veject()^1293: Printing the structure elements eject parms
preview Requested:0

16:17:25.260 [9109] <4> vltrun@veject()^1293: Sleeping 360 secs before starting eject
16:17:25.260 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151445 sent TRY_MSG: VAULT_SLEEP_PRIOR_EJECT 1277151445 360

16:23:25.270 [9109] <2> vltrun@veject()^1293: Sleep over, now will start the actual job,logging in jobMon
16:23:25.270 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151805 sent TRY_MSG: VAULT_SLEEP_AFTER_EJECT 1277151805

16:23:25.270 [9109] <2> vltrun@veject()^1293: Extending the scope of existing job id 77714
16:23:25.270 [9109] <2> vltrun@VaultJobMonitor::_send_jobrundata^1293: SENT JobRunDataEx_t to JOBD at 1277151805
16:23:25.270 [9109] <4> vltrun@VaultJobMonitor::_send_operation^1293: Sent Operation=8 op to Job daemon
16:23:25.270 [9109] <2> vltrun@VaultJobMonitor::_find_op_string^1293: Found 8->EJECT_AND_REPORT
16:23:25.270 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151805 sent TRY_MSG: BEGIN_OPERATION 1277151805 EJECT_AND_REPORT

16:23:25.270 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151805 sent TRY_MSG: CONNECT 1277151805

16:23:25.270 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151805 sent TRY_MSG: CONNECTED 1277151805

16:23:25.295 [9109] <2> vltrun@connectToVRTObject^1293: Connecting to vccs server tmnaim01
16:23:25.299 [9109] <2> vltrun@connectToVRTObject^1293: VaultRuntimePortal init succeeded
16:23:25.299 [9109] <2> vltrun@Consolidate::getSessionsForEject()^1293: Consolidation request is for a session
16:23:25.336 [9109] <2> vltrun@Consolidate::getSessionsForEject()^1293: Returning 1 number of records
16:23:25.364 [9109] <2> vltrun@RobotsFactory::RobotsFactory()^1293: Robots factory received 1 number of session records
16:23:25.364 [9109] <2> vltrun@RobotsFactory::prepareRobotAndVaultObjects()^1293: Creating Robot object for Robot 0
16:23:25.365 [9109] <2> vltrun@VaultRobot::VaultRobot()^1293: >>********** Vault Robot Starts *************<<
16:23:25.365 [9109] <2> vltrun@VaultRobot::VaultRobot()^1293: rob Type = TLD
16:23:25.365 [9109] <2> vltrun@VaultRobot::VaultRobot()^1293: Lower rob Type = tld
16:23:25.366 [9109] <2> vltrun@VaultScsiRobot::VaultScsiRobot()^1293: >>********** Vault SCSI Robot Starts *************<<
16:23:25.366 [9109] <2> vltrun@RobotsFactory::prepareRobotAndVaultObjects()^1293: Creating Vault Session object for Profile:daily_profile and Sessionid:1293
16:23:25.366 [9109] <2> vltrun@VaultSession::VaultSession^1293: Creating a vault session with 0 tmna_vault daily_profile 1293
16:23:25.366 [9109] <2> vltrun@ReportUtil::checkForValidityOfSession()^1293: For SDIR=/usr/openv/netbackup/vault/sessions/tmna_vault/sid1293
16:23:25.366 [9109] <4> vltrun@RobotsFactory::prepareRobotAndVaultObjects()^1293: Created 1 Robot Objects
16:23:25.366 [9109] <4> vltrun@RobotsFactory::prepareRobotAndVaultObjects()^1293: Created 1 Vault Session Objects
16:23:25.366 [9109] <4> vltrun@RobotsFactory::prepareRobotAndVaultObjects()^1293: Attaching vault objects with robot objects
16:23:25.366 [9109] <2> vltrun@VaultRobot::addSessionObjects()^1293: entering function
16:23:25.366 [9109] <2> vltrun@VaultRobot::createNotifyScriptPaths^1293: In
16:23:25.366 [9109] <2> vltrun@VaultRobot::createNotifyScriptPaths^1293: R => 0 V => tmna_vault P=> 0/tmna_vault/daily_profile sid =>1293
16:23:25.366 [9109] <2> vltrun@veject()^1293: Starting with the preview of 0
16:23:25.366 [9109] <2> vltrun@VaultRobot::garnerWorkingMediaSet()^1293: Entering function
16:23:25.366 [9109] <2> vltrun@VaultSession::getMediaForEject()^1293: Opened /usr/openv/netbackup/vault/sessions/tmna_vault/sid1293/eject.list file successfully
16:23:25.383 [9109] <4> vltrun@VaultSession::getMediaForEject()^1293: Loaded 3 media from eject.list file
16:23:25.383 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Eject pool size is 3, and working media map size is 3
16:23:25.383 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000178
16:23:25.391 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000178 #Media=1
16:23:25.391 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Media 000178 is a valid media for eject
16:23:25.391 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000182
16:23:25.397 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000182 #Media=1
16:23:25.398 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Media 000182 is a valid media for eject
16:23:25.398 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000284
16:23:25.404 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000284 #Media=1
16:23:25.404 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Media 000284 is a valid media for eject
16:23:25.404 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Size of media to be removed is 0
16:23:25.404 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: New working media map size is 3
16:23:25.404 [9109] <2> vltrun@VaultRobot::garnerWorkingMediaSet()^1293: Total collected 3 media from all sessions
16:23:25.404 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: RN=0
16:23:25.404 [9109] <2> vltrun@cVolInvMgr::LoadInvByRobotNum^1293: RobNum=0
16:23:25.442 [9109] <4> vltrun@cVolInvMgr::LoadInvByRobotNum^1293: read 23 volumes for RbtNum=0
16:23:25.442 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: ROBOT=TLD(0) contains:
16:23:25.442 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: 000178, 000182, 000284
16:23:25.442 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: Found 3 media in the volume database to eject
16:23:25.443 [9109] <2> vltrun@VaultRobot::updateSessionInfo()^1293: entering function...
16:23:25.443 [9109] <2> vltrun@VaultSession::updateLocalMediaSets()^1293: Local media count is: 3, preview requestd:1
16:23:25.443 [9109] <2> vltrun@veject()^1293: Preview of 0 complete and returned 0
16:23:25.443 [9109] <2> vltrun@veject()^1293: Starting with the eject of 0
16:23:25.443 [9109] <2> vltrun@VaultLockProxy::acquire_lock^1293: Type=VAULT_EJECT.LOCK Key=_0 Limit=1
16:23:25.443 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151805 sent TRY_MSG: VAULT_WAITING_FOR_EJECT_LOCK 1277151805

16:23:27.451 [9109] <4> vltrun@VaultLockProxy::acquire_lock^1293: Lock acquired key=tmnaim01.VAULT_EJECT.LOCK_0
16:23:27.451 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151807 sent TRY_MSG: VAULT_EJECT_LOCK_ACQUIRED 1277151807

16:23:27.451 [9109] <2> vltrun@veject()^1293: Just before calling doEject method **
16:23:27.451 [9109] <2> vltrun@VaultScsiRobot::doEject()^1293: **** started doEject method ****
16:23:27.451 [9109] <2> vltrun@mailEjectStuff()^1293: Entering mailEjectStuff Function
16:23:27.451 [9109] <2> vltrun@mailEjectNotification()^1293: Entering mailEjectNotification
16:23:27.451 [9109] <2> vltrun@mailEjectNotification()^1293: Robot = TLD(0) Vault =tmna_vault Profile =daily_profile sessionId =1293
16:23:27.564 [9109] <2> vltrun@mailEjectNotification()^1293: Success in sending mail
16:23:27.564 [9109] <2> vltrun@VaultRobot::sparkOffNotifyScript^1293: Entering VaultRobot::sparkOffNotifyScript() function
16:23:27.564 [9109] <2> vltrun@VaultRobot::garnerWorkingMediaSet()^1293: Entering function
16:23:27.564 [9109] <2> vltrun@VaultSession::getMediaForEject()^1293: Opened /usr/openv/netbackup/vault/sessions/tmna_vault/sid1293/eject.list file successfully
16:23:27.564 [9109] <4> vltrun@VaultSession::getMediaForEject()^1293: Loaded 3 media from eject.list file
16:23:27.564 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Eject pool size is 3, and working media map size is 3
16:23:27.564 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000178
16:23:27.571 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000178 #Media=1
16:23:27.571 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Media 000178 is a valid media for eject
16:23:27.571 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000182
16:23:27.577 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000182 #Media=1
16:23:27.577 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Media 000182 is a valid media for eject
16:23:27.577 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: M=000284
16:23:27.584 [9109] <2> vltrun@cVolInvMgr::LoadInvByMediaId^1293: Loaded M=000284 #Media=1
16:23:27.584 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Media 000284 is a valid media for eject
16:23:27.584 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: Size of media to be removed is 0
16:23:27.584 [9109] <2> vltrun@VaultRobot::validateEjectMediaSet^1293: New working media map size is 3
16:23:27.584 [9109] <2> vltrun@VaultRobot::garnerWorkingMediaSet()^1293: Total collected 3 media from all sessions
16:23:27.584 [9109] <4> vltrun@VaultScsiRobot::doEject()^1293: Total Number of Volumes found in eject.list are 3
16:23:27.584 [9109] <2> vltrun@VaultRobot::suspendMediaToEject()^1293: entering function
16:23:27.584 [9109] <2> vltrun@cNbInvMgr::LoadMediaSet^1293: #Media=3
16:23:28.288 [9109] <4> vltrun@cNbInvMgr::LoadMediaSet^1293: Added 3 records to nbInvList
16:23:28.288 [9109] <2> vltrun@VaultRobot::suspendMediaToEject()^1293: For media 000178, suspend host is tmnaim01
16:23:28.288 [9109] <2> vltrun@VaultRobot::suspendMediaToEject()^1293: MEDIA=000178 with HOST=tmnaim01 is ALREADY suspended, ignoring...
16:23:28.288 [9109] <2> vltrun@VaultRobot::suspendMediaToEject()^1293: For media 000182, suspend host is tmnaim01
16:23:28.288 [9109] <2> vltrun@VaultRobot::suspendMediaToEject()^1293: MEDIA=000182 with HOST=tmnaim01 is ALREADY suspended, ignoring...
16:23:28.288 [9109] <2> vltrun@VaultRobot::suspendMediaToEject()^1293: For media 000284, suspend host is tmnaim01
16:23:28.288 [9109] <2> vltrun@SuspendMedia^1293: suspending media 000284 with host tmnaim01
16:23:28.341 [9109] <2> vltrun@SuspendMedia^1293: media 000284 with host tmnaim01 is suspended successfully
16:23:28.341 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: RN=0
16:23:28.342 [9109] <2> vltrun@cVolInvMgr::LoadInvByRobotNum^1293: RobNum=0
16:23:28.359 [9109] <4> vltrun@cVolInvMgr::LoadInvByRobotNum^1293: read 23 volumes for RbtNum=0
16:23:28.359 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: ROBOT=TLD(0) contains:
16:23:28.359 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: 000178, 000182, 000284
16:23:28.359 [9109] <2> vltrun@VaultRobot::pruneMediaBasedOnDbInfo^1293: Found 3 media in the volume database to eject
16:23:28.359 [9109] <2> vltrun@VaultScsiRobot::doEject()^1293: The total number of Volumes found in the Robot are 3
16:23:28.359 [9109] <4> vltrun@VaultScsiRobot::doEject()^1293: working_media_count:3
16:23:28.359 [9109] <2> vltrun@VaultJobMonitor::_send_try_msg^1293: At 1277151808 sent TRY_MSG: VAULT_EJECT_START 1277151808 3

16:23:28.359 [9109] <2> vltrun@VaultJobMonitor::_send_jobdata^1293: SENT JobDataEx_t to JOBD at 1277151808
16:23:28.359 [9109] <4> vltrun@VaultJobMonitor::UpdateOperationTodo^1293: SENT TapesToEject to JOBD: 3
16:23:28.359 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:23:28.359 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:23:37.738 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: entering function
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: There are no free MAP elements to eject volumes into....
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: But retrying to find if any free elems are present..
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:23:47.905 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:23:47.919 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:23:47.919 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:24:17.920 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:24:17.920 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:24:27.930 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:24:27.945 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:24:27.945 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:24:57.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:24:57.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:24:58.414 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executed /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01 comamnd
16:24:58.414 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 57088 (223)
16:24:58.414 [9109] <16> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Ran out of retries, giving up, setting free map elems = 0
16:25:28.420 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:25:28.420 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:25:37.815 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:25:37.827 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:25:37.827 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:26:07.830 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:26:07.830 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:26:17.305 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:26:17.317 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:26:17.317 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:26:47.320 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:26:47.320 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:26:56.947 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:26:56.961 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:26:56.961 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:27:26.970 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:27:26.970 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:27:36.625 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:27:36.639 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:27:36.639 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:28:06.640 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:28:06.640 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:28:15.969 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:28:15.983 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:28:15.983 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:28:45.990 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:28:45.990 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:28:55.447 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:28:55.460 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:28:55.460 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:29:25.470 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:29:25.470 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:29:34.925 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:29:34.940 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:29:34.940 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:30:04.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:30:04.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:30:14.435 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:30:14.449 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:30:14.449 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:30:44.450 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:30:44.450 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:30:53.938 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:30:53.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:30:53.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:31:23.960 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:31:23.960 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:31:33.457 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:31:33.478 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:31:33.478 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:32:03.480 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:32:03.480 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:32:12.933 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:32:12.945 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:32:12.945 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:32:42.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:32:42.950 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:32:52.980 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:32:52.994 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:32:52.994 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:33:23.000 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:33:23.000 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:33:32.503 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:33:32.516 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:33:32.516 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:34:02.520 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:34:02.520 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:34:12.001 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:34:12.001 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:34:12.001 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:34:42.010 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:34:42.010 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:34:51.492 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:34:51.506 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:34:51.506 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:35:21.510 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:35:21.510 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:35:30.941 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:35:30.955 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:35:30.955 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:36:00.984 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:36:00.984 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:36:10.450 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:36:10.463 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:36:10.463 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:36:40.470 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:36:40.470 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:36:50.010 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:36:50.022 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:36:50.022 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:37:20.030 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:37:20.039 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:37:29.532 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:37:29.545 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:37:29.545 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:37:59.550 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:37:59.550 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:38:09.021 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:38:09.033 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:38:09.033 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:38:39.040 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:38:39.040 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:38:48.557 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:38:48.570 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:38:48.570 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:39:18.580 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:39:18.580 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:39:28.084 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:39:28.104 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:39:28.104 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:39:58.110 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:39:58.110 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:39:58.566 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executed /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01 comamnd
16:39:58.566 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 57088 (223)
16:39:58.566 [9109] <16> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Ran out of retries, giving up, setting free map elems = 0
16:40:28.570 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:40:28.570 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:40:37.997 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:40:38.012 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:40:38.012 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:41:08.020 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:41:08.020 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:41:17.520 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:41:17.533 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:41:17.533 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:41:47.540 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:41:47.540 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:41:57.029 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:41:57.042 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:41:57.042 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:42:27.050 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:42:27.050 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:42:36.666 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:42:36.672 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:42:36.672 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:43:06.680 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:43:06.680 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:43:16.007 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:43:16.020 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:43:16.020 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:43:46.030 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:43:46.030 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:43:55.487 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:43:55.500 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:43:55.500 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:44:25.510 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:44:25.510 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:44:35.047 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:44:35.060 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:44:35.073 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:45:05.080 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:45:05.080 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:45:14.489 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:45:14.502 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:45:14.502 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:45:44.510 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:45:44.510 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:45:54.003 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:45:54.018 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:45:54.018 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:46:24.020 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:46:24.020 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:46:33.463 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:46:33.483 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:46:33.483 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:47:03.490 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:47:03.490 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:47:12.973 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:47:12.986 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:47:12.986 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:47:42.990 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:47:42.990 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:47:52.502 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:47:52.515 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:47:52.515 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:48:22.520 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:48:22.520 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:48:32.102 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:48:32.116 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:48:32.116 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:49:02.120 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:49:02.120 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:49:11.479 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: robNum: 0, robType: TLD, MapElems: 6, MapFreeElems: 0
16:49:11.494 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Command returned: 0 (0)
16:49:11.494 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Successfully read in ROBOT_INFO
16:49:41.500 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Prepared the command to get robot info: /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01
16:49:41.500 [9109] <2> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Executing /usr/openv/volmgr/bin/vmchange -res -robot_info -rn 0 -rt tld -rh tmnaim01 -vh tmnaim01

Will_Restore · ‎06-22-2010

16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: There are no free MAP elements to eject volumes into....
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: But retrying to find if any free elems are present..
16:24:58.414 [9109] <16> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Ran out of retries, giving up, setting free map elems = 0

something already in the MAP (CAP) ??

View solution in original post

r1 · ‎06-21-2010

see attachment for more info

Sriram · ‎06-21-2010

The attachement opens ZIP format and there so many xml files.

Pls describe your issue in more detail rather than a single line or reattach something meaningful which describes your prbolem.

Will_Restore · ‎06-22-2010

16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: There are no free MAP elements to eject volumes into....
16:23:37.751 [9109] <2> vltrun@VaultScsiRobot::doStartEject()^1293: But retrying to find if any free elems are present..
16:24:58.414 [9109] <16> vltrun@VaultScsiRobot::gatherRobotInfo()^1293: Ran out of retries, giving up, setting free map elems = 0

something already in the MAP (CAP) ??

Will_Restore · ‎06-23-2010

...to check the library, I guess

David_McMullin · ‎06-24-2010

If you have a robotic library, try opening and closing the access port, and verifying it is set correctly.

ALSO - reinventory the library after the failed vault - NetBackup thinks the tapes are ejected, and you need to run the inventory so it knows they are still in the library!

I have an SL8500 with acsls, on the acsls I can issue this command:

ACSSA> q cap all
2010-06-24 08:05:46                CAP Status
Identifier   Priority Size State            Mode       Status
   0, 1, 0    0         39    online           automatic available
   0, 1, 1    0         39    offline          manual     available
ACSSA>

My CAP is 0,1,0 and it is online and in automatic mode - if it is offline or manual, the vault will not eject.

Sometimes the CAP seems closed but for some reason it will not load - opening and closing the CAP seems to resolve this.
My OPS process is to run the vault, and if it does not eject to open and close the CAP, then re-inventory the robot, then re-run the vault.
It has taken up to 3 vault runs on occasion...

r1 · ‎06-25-2010

wrobbins , you had it , tapes were already in the CAP , NBU was confused and never ejected those tapes correctly at the first previous , previous daily vault jobs so they stayed in the MAP and when next job ran it couldn't eject the rest of the tapes . Thanks again

Marianne · ‎06-25-2010

Please mark Bill's post as solution?

Handy NetBackup Links

VOX

Vault Jobs don't eject Tapes