01:54:55.027 [760.1552] <2> bpsynth: INITIATING -C gzw8r2a -jobid 243 -pl NBU_Catalyst_Full -sched Synthetic_Full -sn 0 -slpVER 0 -P NetBackup -S B6200_NBU_CATALYST_STORE1 -owner *ANY* -nbu (bpsynth.cpp:182)
01:54:55.027 [760.1552] <2> bpsynth: VERBOSE = 0, TRACING = 0(bpsynth.cpp:184)
01:54:55.059 [760.1552] <2> JobMonProxy::connect: Jobd host= gzw8r2a.ebs.net(JobMonProxy.cpp:234)
01:54:55.074 [760.1552] <2> job_connect: SO_KEEPALIVE set on socket 700 for client gzw8r2a.ebs.net
01:54:55.074 [760.1552] <2> logconnections: BPJOBD CONNECT FROM 10.0.0.110.63132 TO 10.0.0.110.13723 fd = 700
01:54:55.074 [760.1552] <2> job_authenticate_connection: ignoring VxSS authentication check for now...
01:54:55.074 [760.1552] <2> job_connect: Connected to the host gzw8r2a.ebs.net contype 53 jobid <243> socket <700>
01:54:55.074 [760.1552] <2> job_connect: Connected on port 63132
01:54:55.074 [760.1552] <2> JobMonProxy::connect: Successfully connected to Job monitor(JobMonProxy.cpp:250)
01:54:55.074 [760.1552] <2> SynthJob::readSecondaryConfig: Unable to open C:\Program Files\Veritas\NetBackup\db\config\multi_synth.NBU_Catalyst_Full.Synthetic_Full(bpsynth.cpp:687)
01:54:55.074 [760.1552] <2> JobMonProxy::sendTryMsg: sent try msg to jobd: BEGIN_OPERATION 1341471295 SYNTHETIC_BACKUP
(JobMonProxy.cpp:584)
01:54:55.074 [760.1552] <2> JobMonProxy::send_operation: Sent Operation= 19 to Job daemon(JobMonProxy.cpp:703)
01:54:55.074 [760.1552] <2> JobMonProxy::find_op_string: Found 19->QUERY_DATABASE(JobMonProxy.cpp:673)
01:54:55.074 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd(JobMonProxy.cpp:630)
01:54:55.074 [760.1552] <2> ConnectionCache::connectAndCache: Acquiring new connection for host gzw8r2a.ebs.net, query type 200
01:54:55.090 [760.1552] <2> logconnections: BPDBM CONNECT FROM 10.0.0.110.63135 TO 10.0.0.110.13721 fd = 724
01:54:55.090 [760.1552] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
01:54:55.121 [760.1552] <4> TZ: Send Timezone offset=18000
01:54:55.137 [760.1552] <4> TZ: Recv Timezone offset=18000-local=18000
01:54:55.137 [760.1552] <4> db_CLASSunmarshal: Remote timezone offset = 18000
01:54:55.137 [760.1552] <4> db_CLASSunmarshal: Remote timezone offset = 18000
01:54:55.137 [760.1552] <4> db_CLASSunmarshal: Remote timezone offset = 18000
01:54:55.137 [760.1552] <2> SynthJob::doStart: policy record is: Attributes: 
Client Type=13
Follow NFS=0
Client Compress=0
Priority=0
Client Encrypt=0
Disaster Recovery=0
Max Jobs Per Class=2147483647
Cross Mount Points=0
MaxFragSize=0
Active=0
Collect TIR=2
Block Incr=0
Streaming=0
Share Group=*ANY*
Allow Storage Services=0
Schedules: 
Label=Full
SchedType=0
MaxMPX=0
Frequency=604800
RetentionLevel=0
IncrementalType=0
MaxFragSizeMB=0
Label=Synthetic_Full
SchedType=0
MaxMPX=0
Frequency=604800
RetentionLevel=0
IncrementalType=0
MaxFragSizeMB=0
Label=Incremental
SchedType=1
MaxMPX=1
Frequency=604800
RetentionLevel=0
IncrementalType=0
MaxFragSizeMB=0
(SynthJob.cpp:252)
01:54:55.137 [760.1552] <2> ConnectionCache::connectAndCache: Acquiring new connection for host gzw8r2a.ebs.net, query type 192
01:54:55.137 [760.1552] <2> logconnections: BPDBM CONNECT FROM 10.0.0.110.63136 TO 10.0.0.110.13721 fd = 732
01:54:55.137 [760.1552] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
01:54:55.199 [760.1552] <4> TZ: Send Timezone offset=18000
01:54:55.199 [760.1552] <4> TZ: Recv Timezone offset=18000-local=18000
01:54:55.199 [760.1552] <2> SynthJob::doStart: found schedule = Full type = 0(SynthJob.cpp:299)
01:54:55.199 [760.1552] <2> SynthJob::doStart: found schedule = Synthetic_Full type = 0(SynthJob.cpp:299)
01:54:55.199 [760.1552] <2> SynthJob::doStart: found schedule = Incremental type = 1(SynthJob.cpp:299)
01:54:55.199 [760.1552] <2> Default Retention: No user retention file
01:54:55.199 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd: END_OPERATION 1341471295
(JobMonProxy.cpp:657)
01:54:55.199 [760.1552] <2> JobMonProxy::send_operation: Sent Operation= 20 to Job daemon(JobMonProxy.cpp:703)
01:54:55.199 [760.1552] <2> JobMonProxy::find_op_string: Found 20->PROCESS_EXTENTS(JobMonProxy.cpp:673)
01:54:55.199 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd(JobMonProxy.cpp:630)
01:54:55.199 [760.1552] <2> JobMonProxy::UpdateCompletionPercentage: sent completion pct = 5(JobMonProxy.cpp:777)
01:54:55.199 [760.1552] <2> ConnectionCache::connectAndCache: Acquiring new connection for host gzw8r2a.ebs.net, query type 148
01:54:55.199 [760.1552] <2> logconnections: BPDBM CONNECT FROM 10.0.0.110.63137 TO 10.0.0.110.13721 fd = 736
01:54:55.199 [760.1552] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
01:54:55.932 [760.1552] <2> SynthJob::updateMediaList: adding media id: @aaaab(SynthJob.cpp:743)
01:54:55.932 [760.1552] <2> dsmlibp_emmserver: EMM server set to gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsmlibp_masterserver: Master server set to gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsmlibp_connect_dsm: Connecting to DiskServiceMgr on gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsmlibp_get_orb: Using dsmlib private orb(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsmlibp_connect_dsm: Connecting to NBDSMFSM using orb 000000000114F770(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsmlibp_register_factories: Registerd Framework OBV factories with orb 000000000114F770(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsmlibp_register_factories: Registerd DiskServiceMgr OBV factories with orb 000000000114F770(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsm_get_diskvolumeinfo_by_mediaid: diskvolume media ID = @aaaab(bpsynth.cpp:80)
01:54:55.932 [760.1552] <2> dsm_get_diskvolumeinfo_by_mediaid: Calling dsm->getDiskVolumeInfoByMediaId()(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolume.name = B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolume.id = B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.total_capacity = 30540196590237(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.free_space = 30536442699005(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::master, property.value=gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::flags, property.value=14(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.nbu_state = conv_fn(1)(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::fileSystemType, property.value=0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::fileSystemOrdinal, property.value=0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.max_write_mounts = 1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.num_write_mounts = 1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::maxReferenceWriters, property.value=2147483647(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.max_read_mounts = 0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.num_read_mounts = 0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::maxReferenceReaders, property.value=0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::freeSpace, property.value=30536442699005(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::freePhysicalSpace, property.value=30536442699005(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.potential_free_space = 0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.committed_space = 0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.precommitted_space = 0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.system_tag = Unknown(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: diskvolumeinfo.disk_media_id = @aaaab(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::activeReadStreams, property.value=0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::activeWriteStreams, property.value=0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::createdTime, property.value=1340996351(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::lastModifiedTime, property.value=1341471258(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::totalPhysicalCapacity, property.value=30540196590237(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::totalCapacity, property.value=30540196590237(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSMi::ReplicationSourceAndTargetSpecCount, property.value=0(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::majorEventSyncToken, property.value=1341471258(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::minorEventSyncToken, property.value=1932(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_extract_diskvolumeinfo: property.name=DSM::diskGroupName, property.value=B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsm_get_diskvolumeinfo_by_mediaid: diskvolumeinfo.diskgroup.name = B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsm_get_diskvolumeinfo_by_mediaid: diskvolumeinfo.diskgroup.master_server = gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsm_get_diskvolumeinfo_by_mediaid: diskvolumeinfo.diskgroup.server_type = hp-StoreOnceCatalyst(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_populate_diskgroup: diskgroup.name = B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_populate_diskgroup: diskgroup.master_server = gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_populate_diskgroup: diskgroup.server_type = hp-StoreOnceCatalyst(bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsml_populate_diskgroup: diskgroup.access_media_server = (bpsynth.cpp:80)
01:54:55.948 [760.1552] <2> dsm_get_diskgroupinfo: Calling dsm->getDiskGroupInfo()(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsm_get_diskgroupinfo: Storing new DiskGroupInfo object reference(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroup.name = B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroup.id = B6200_NBU_CATALYST_STORE1(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroup.master_server = gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroup.server_type = hp-StoreOnceCatalyst(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.total_capacity = 30540196590237(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.used_space = 0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::master, property.value=gzw8r2a.ebs.net(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.nbu_state = conv_fn(2)(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::flags, property.value=1137911(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::flagsExt, property.value=0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::fileSystemCardinal, property.value=0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::usedSpace, property.value=0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::usedPhysicalSpace, property.value=0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.high_watermark = 98(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.low_watermark = 80(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.system_tag = Created by NetBackup(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::maxIOStreams, property.value=-1(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::servertype, property.value=hp-StoreOnceCatalyst(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::createdTime, property.value=1340996351(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::lastModifiedTime, property.value=0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::totalPhysicalCapacity, property.value=30540196590237(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::totalCapacity, property.value=30540196590237(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.availability = !Yes(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::majorEventSyncToken, property.value=1341471258(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: property.name=DSM::minorEventSyncToken, property.value=1932(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.num_diskvolumes = 1(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.num_disks = 0(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> dsml_extract_diskgroupinfo: diskgroupinfo.num_stservers = 1(bpsynth.cpp:80)
01:54:55.979 [760.1552] <2> SynthJob::updateMediaList: flags = 1137911(SynthJob.cpp:751)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: Start(SynthJob.cpp:780)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: End(SynthJob.cpp:829)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: Start(SynthJob.cpp:780)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: End(SynthJob.cpp:829)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: Start(SynthJob.cpp:780)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: End(SynthJob.cpp:829)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: Start(SynthJob.cpp:780)
01:54:55.979 [760.1552] <2> SynthJob::updateSpannedList: End(SynthJob.cpp:829)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: backup id of synthetic image is = 1341470986(SynthJob.cpp:896)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: map now has 4 image entries and 1 media entries(SynthJob.cpp:899)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: backup gzw8r2a_1341470985(SynthJob.cpp:905)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: backup gzw8r2a_1341470923(SynthJob.cpp:905)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: backup gzw8r2a_1341470853(SynthJob.cpp:905)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: backup gzw8r2a_1341470659(SynthJob.cpp:905)
01:54:55.979 [760.1552] <2> SynthJob::processNewImages: media @aaaab(SynthJob.cpp:911)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: ==1341470985==(SynthJob.cpp:1036)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: **FRAGMENT 1(SynthJob.cpp:1038)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: FRAG, start = 0, end=6975(SynthJob.cpp:1041)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: ==1341470923==(SynthJob.cpp:1036)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: **FRAGMENT 1(SynthJob.cpp:1038)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: FRAG, start = 0, end=7511(SynthJob.cpp:1041)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: ==1341470853==(SynthJob.cpp:1036)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: **FRAGMENT 1(SynthJob.cpp:1038)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: FRAG, start = 0, end=6981(SynthJob.cpp:1041)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: ==1341470659==(SynthJob.cpp:1036)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: **FRAGMENT 1(SynthJob.cpp:1038)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: FRAG, start = 0, end=10095309(SynthJob.cpp:1041)
01:54:55.979 [760.1552] <2> SynthJob::makeExtents: total blocks in synthetic image = 10095310(SynthJob.cpp:1106)
01:54:55.979 [760.1552] <2> SynthJob::prepareImages: there are 1164 extents(SynthJob.cpp:632)
01:54:55.979 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd: END_OPERATION 1341471295
(JobMonProxy.cpp:657)
01:54:55.979 [760.1552] <2> JobMonProxy::UpdateCompletionPercentage: sent completion pct = 10(JobMonProxy.cpp:777)
01:54:55.979 [760.1552] <2> SynthJob::obtainResources:  loop @aaaab, host = gzw8r2a.ebs.net, host level = 750100(SynthJob.cpp:1285)
01:54:55.979 [760.1552] <2> SynthJob::obtainResources: Add CMediaReserveRequest(id=@aaaab, host=gzw8r2a.ebs.net, jobType=10, failOnError=1, count=4, Affinity Id=0)(SynthJob.cpp:1319)
01:54:55.979 [760.1552] <2> SynthJob::obtainResources: Add CInitialMediaSelectRequest(id=@aaaab, host=gzw8r2a.ebs.net, jobType=10, failOnError=1, mediaType=0, mediaSubType=6)(SynthJob.cpp:1348)
01:54:55.979 [760.1552] <2> SynthJob::obtainResources: Add CInitialStuSelectRequest(stu=B6200_NBU_CATALYST_STORE1, mediaServer=, pool=NetBackup, shareGroup=*ANY*, usageType= 9, retentionLevel=0, failOnError=1)(SynthJob.cpp:1392)
01:54:55.979 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd: END_OPERATION 1341471295
(JobMonProxy.cpp:657)
01:54:55.979 [760.1552] <2> JobMonProxy::send_operation: Sent Operation= 26 to Job daemon(JobMonProxy.cpp:703)
01:54:55.979 [760.1552] <2> JobMonProxy::find_op_string: Found 26->REQUEST_JOB_RESOURCES(JobMonProxy.cpp:673)
01:54:55.979 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd(JobMonProxy.cpp:630)
01:54:57.586 [760.1552] <2> RequestInitialResources: returning(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> SynthJob::obtainResources: RequestInitialResources call returned 0(SynthJob.cpp:1425)
01:54:57.586 [760.1552] <2> PrintMdrInfo: requestStatus is 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdr_AllocationKey : 211(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.TpReqFileName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo:  m_MediaAllocation.mdar.Mdr.MediaServer : gzw8r2a.ebs.net(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MediaServerVersion : 750100(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo:  m_MediaAllocation.mdar.Mdr.MediaServerClusterName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MediaKey : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MediaId : @aaaab(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.DriveKey : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.DriveName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.PrimaryPath : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.PoolName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.NdmpControlHost : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.NdmpUserName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.NdmpClientUserName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.RetentionLevel : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.PolicyType : 2(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.JobType : 9(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MasterServer : gzw8r2a.ebs.net(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.STUName : B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.MasterServer : gzw8r2a.ebs.net(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.STUType : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.MediaServer : gzw8r2a.ebs.net(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.RobotType : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.RobotNumber : -1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.Density : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.DriveGroupName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.ConcurrentJobs : 1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.ActiveJobs : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.AbsolutePath : B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.InitialMultiplexing : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.OnDemandOnly : 1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.MaxMultiplexing : 1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.MaxFragmentSize : 524288(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.NdmpAttachHost : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.StuFlags : 0x0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.StuSubtype : 6(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.Throttle : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.HighWaterMark : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.LowWaterMark : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo:  m_MediaAllocation.mdar.Stu.OkOnRoot : 1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo: useFatPipeTransport is 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrStuInfo: diskPath is B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[0] : MEDIADB 1 211 *NULL* 0 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[1] : VOLUME 1 *NULL* 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[2] : DRIVE 3 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 1 0 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[3] : STORAGE 1 B6200_NBU_CATALYST_STORE1 0 524288 0 6 0 0 gzw8r2a.ebs.net gzw8r2a.ebs.net *NULL*(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[4] : DISKGROUP 0 20 hp-StoreOnceCatalyst 9 10.0.0.14 25 B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[5] : DISKVOLUME 0 6 @aaaab 25 B6200_NBU_CATALYST_STORE1 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[6] : DISKMOUNTPOINT 0 25 B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> SynthJob::obtainResources: CInitialStuSelectRequest : ReservationStatus = 0, Target Media Server= gzw8r2a.ebs.net(SynthJob.cpp:1453)
01:54:57.586 [760.1552] <2> PrintMdrInfo: requestStatus is 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdr_AllocationKey : 210(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.TpReqFileName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo:  m_MediaAllocation.mdar.Mdr.MediaServer : gzw8r2a.ebs.net(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MediaServerVersion : 750100(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo:  m_MediaAllocation.mdar.Mdr.MediaServerClusterName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MediaKey : 1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MediaId : @aaaab(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.DriveKey : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.DriveName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.PrimaryPath : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.PoolName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.NdmpControlHost : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.NdmpUserName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.NdmpClientUserName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.RetentionLevel : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.PolicyType : 2(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.JobType : 10(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrMediaInfo: m_MediaAllocation.mdar.Mdr.MasterServer : gzw8r2a.ebs.net(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[0] : MEDIADB 1 210 @aaaab 1 *NULL* 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[1] : VOLUME 1 @aaaab 1 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 {00000000-0000-0000-0000-000000000000} 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[2] : DRIVE 3 *NULL* 0 *NULL* *NULL* 0 0 0 0 0 0 0 0 *NULL* *NULL* *NULL* *NULL* 0 0 0 0 0 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[3] : STORAGE 1 *NULL* 0 0 0 0 0 0 gzw8r2a.ebs.net gzw8r2a.ebs.net *NULL*(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[4] : DISKGROUP 0 20 hp-StoreOnceCatalyst 9 10.0.0.14 25 B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[5] : DISKVOLUME 0 6 @aaaab 25 B6200_NBU_CATALYST_STORE1 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> PrintMdrInfo: BptmStrings[6] : DISKMOUNTPOINT 0 25 B6200_NBU_CATALYST_STORE1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> SynthJob::obtainResources: Read media server unchanged: gzw8r2a.ebs.net(SynthJob.cpp:1480)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: requestStatus is 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.mediaServer : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.mediaId : @aaaab(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.mediaKey : 1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.userReservationId : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.assignedTime : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.client : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.usageType : 0(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.mustBeNdmp : false(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.driveName : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.drivePath : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.mediaPool : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.robotNumber : -1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.slotNumber : -1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.density : -1(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.ndmpControlHost : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.failIfNoMedia : false(SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> CMediaReserveRequest::PrintReservation: m_MediaReservation.mediaReq.externalFile : (SynthJob.cpp:64)
01:54:57.586 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd: END_OPERATION 1341471297
(JobMonProxy.cpp:657)
01:54:57.586 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd: END_OPERATION 1341471297
(JobMonProxy.cpp:657)
01:54:57.586 [760.1552] <2> JobMonProxy::send_operation: Sent Operation= 21 to Job daemon(JobMonProxy.cpp:703)
01:54:57.586 [760.1552] <2> JobMonProxy::find_op_string: Found 21->ORGANIZE_READERS(JobMonProxy.cpp:673)
01:54:57.586 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd(JobMonProxy.cpp:630)
01:54:57.617 [760.1552] <2> JobMonProxy::sendTryMsg: sent try msg to jobd: LOG 1341471297 4 bpsynth 760 Performing Optimized Synthetic Operation
(JobMonProxy.cpp:584)
01:54:57.617 [760.1552] <2> SynthJob::doStart: Output Volume STU=B6200_NBU_CATALYST_STORE1, Media Host=gzw8r2a.ebs.net, Retention Level=0 optimized=true(SynthJob.cpp:499)
01:54:57.617 [760.1552] <2> PlanAdmin::plan2: creating initial plan for jobid=243(PlanAdmin.cpp:100)
01:54:57.617 [760.1552] <2> VirtualPlanner::plan: add virtual read action for backupid = gzw8r2a_1341470985, start block=0(BasicPlanner.cpp:169)
01:54:57.617 [760.1552] <2> VirtualPlanner::plan: add virtual read action for backupid = gzw8r2a_1341470923, start block=6954(BasicPlanner.cpp:169)
01:54:57.617 [760.1552] <2> VirtualPlanner::plan: add virtual read action for backupid = gzw8r2a_1341470853, start block=6(BasicPlanner.cpp:169)
01:54:57.617 [760.1552] <2> VirtualPlanner::plan: add virtual read action for backupid = gzw8r2a_1341470659, start block=6(BasicPlanner.cpp:169)
01:54:57.617 [760.1552] <2> VirtualPlanner::plan: total blocks to read = 10095310(BasicPlanner.cpp:176)
01:54:57.617 [760.1552] <2> JobMonProxy::sendTryMsg: sent try msg to jobd: START_SYNTH_WRITER 1341471297 gzw8r2a_1341470986
(JobMonProxy.cpp:584)
01:54:57.617 [760.1552] <2> logconnections: BPCD CONNECT FROM 10.0.0.110.63148 TO 10.0.0.110.13782 fd = 1008
01:54:57.664 [760.1552] <2> ImageEndpoint::connect: connected to bpcd on: gzw8r2a.ebs.net(ImageEndpoint.cpp:119)
01:54:57.664 [760.1552] <2> ImageEndpoint::connect: fds for tmOutput and tmInput are 992 and 1008(ImageEndpoint.cpp:121)
01:54:57.664 [760.1552] <2> ACEMsgTran::updateReactor_u: setting mask on handler: handle = 00000000000003E0 mask = 0(ACEMsgTran.cpp:393)
01:54:57.664 [760.1552] <2> ACEMsgTran::updateReactor_u: setting mask on handler: handle = 00000000000003E0 mask = 0(ACEMsgTran.cpp:393)
01:54:57.664 [760.1552] <2> ACEMsgTran::updateReactor_u: setting mask on handler: handle = 00000000000003F0 mask = 0(ACEMsgTran.cpp:393)
01:54:57.664 [760.1552] <2> ACEMsgTran::updateReactor_u: setting mask on handler: handle = 00000000000003E0 mask = 1(ACEMsgTran.cpp:393)
01:54:57.664 [760.1552] <2> ACEMsgTran::updateReactor_u: setting mask on handler: handle = 00000000000003F0 mask = 0(ACEMsgTran.cpp:393)
01:54:57.664 [760.1552] <2> WriteVirtualSynth::startWriter: AIR:0 job:0 target:gzw8r2a.ebs.net media:1 disk:1(WriteSynth.cpp:667)
01:54:57.664 [760.1552] <2> TMVProxy::writeData: starting command: cmd = /usr/openv/netbackup/bin/bptm bptm -v -cmd -nosig -w -maxfrag 524288 -mediasvr gzw8r2a.ebs.net -cj 1 -dpath B6200_NBU_CATALYST_STORE1 -stunit B6200_NBU_CATALYST_STORE1 -synthjobhost gzw8r2a.ebs.net -synth 1164 -virtual_synth -c gzw8r2a -ct 13 -cl NBU_Catalyst_Full -bt 1341470986 -st 0 -sl Synthetic_Full -ru root -rclnt gzw8r2a -rclnthostname gzw8r2a -rl 0 -rp 604800 -jobid 243 -masterversion 750000 -b gzw8r2a_1341470986 -tir(TMProxy.cpp:876)
01:54:57.664 [760.1552] <2> JobMonProxy::UpdateCompletionPercentage: sent completion pct = 20(JobMonProxy.cpp:777)
01:54:57.664 [760.1552] <2> JobMonProxy::sendTryMsg: sent try msg to jobd: START_SYNTH_READER 1341471297 gzw8r2a_1341470985 @aaaab 1
(JobMonProxy.cpp:584)
01:54:57.664 [760.1552] <2> ReadVirtualExtents::execute: executing: PlanAction: 1 state = Waiting status = 0 startTime = 0 stopTime = 0 
ReadVirtualExtents: backup = gzw8r2a_1341470985 startBlock = 0 first extent = 0 last extent = 0 media = @aaaab(ReadExtents.cpp:933)
01:54:57.664 [760.1552] <2> JobMonProxy::sendTryMsg: sent try msg to jobd: EXTENTS 1341471297 0 0 gzw8r2a_1341470985
(JobMonProxy.cpp:584)
01:54:57.664 [760.1552] <2> MailboxMsgPort::getData: outbox is now empty(MailboxMsgPort.cpp:101)
01:54:57.664 [760.1552] <2> ReadVirtualExtents::sendExtents: sending remaining 1 extents(ReadExtents.cpp:1288)
01:54:57.664 [760.1552] <2> MailboxMsgPort::getData: outbox is now empty(MailboxMsgPort.cpp:101)
01:54:57.664 [760.1552] <2> MailboxMsgPort::getData: outbox is now empty(MailboxMsgPort.cpp:101)
01:54:57.664 [760.1552] <2> JobMonProxy::send_begin_op_try_msg: sent try msg to jobd: END_OPERATION 1341471297
(JobMonProxy.cpp:657)
01:54:57.757 [760.1552] <2> ImageEndpoint::checkMsg: received msg=PID of bpxm = 7448(ImageEndpoint.cpp:253)
01:54:59.645 [760.1552] <2> WriteVirtualSynth::handleEndpointMsg: writer parent msg = Msg: type = 5(WriteSynth.cpp:554)
01:54:59.645 [760.1552] <2> WriteVirtualSynth::handleEndpointMsg: received exit msg from writer parent, status = 84(WriteSynth.cpp:585)
01:54:59.676 [760.1552] <16> WriteVirtualSynth::handleEndpointMsg: received EXIT message from writer parent with error status=media write error(84)
01:54:59.692 [760.1552] <16> WriteVirtualSynth::giveEndpointStatus: received end point error = media write error(84)
01:54:59.692 [760.1552] <2> WriteVirtualSynth::finish: finished: PlanAction: 0 state = Finished status = 84 startTime = 1341471297 stopTime = 1341471299 run time = 2 
WriteVirtualSynth: (WriteSynth.cpp:718)
01:54:59.723 [760.1552] <16> PlanAdmin::actionDone: action failed, error=media write error(84)
01:54:59.723 [760.1552] <2> bpsynth: broke out of ACE Reactor, EXIT_status = 84(bpsynth.cpp:436)
01:54:59.723 [760.1552] <2> ReadVirtualExtents::abort: aborting action: 1(ReadExtents.cpp:1014)
01:55:14.730 [760.1552] <2> SynthJob::cleanup: delete incomplete synthetic image = 1341470986(SynthJob.cpp:1241)
01:55:14.730 [760.1552] <2> ConnectionCache::connectAndCache: Acquiring new connection for host gzw8r2a.ebs.net, query type 87
01:55:14.730 [760.1552] <2> logconnections: BPDBM CONNECT FROM 10.0.0.110.63181 TO 10.0.0.110.13721 fd = 988
01:55:14.730 [760.1552] <8> vnet_check_vxss_client_magic_with_info: [vnet_vxss_helper.c:871] Ignoring VxSS authentication 2 0x2
01:55:16.649 [760.1552] <2> SynthJob::deleteDBMImage(): deleted image 1341470986(SynthJob.cpp:1155)
01:55:16.665 [760.1552] <16> SynthJob::cleanup: end bpsynth with status = media write error(84)
01:55:16.665 [760.1552] <2> JobMonProxy::sendTryMsg: sent try msg to jobd: END_OPERATION 1341471316
(JobMonProxy.cpp:584)
01:55:16.665 [760.1552] <2> SynthJob::releaseResources: Begin: jmcomm_ReleaseAllResourcesForJob(SynthJob.cpp:1702)
01:55:16.665 [760.1552] <2> jmcomm_ReleaseAllResourcesForJob: releaseAllResources returns [0](SynthJob.cpp:64)
01:55:16.665 [760.1552] <2> jmcomm_ReleaseAllResourcesForJob: returning(SynthJob.cpp:64)
01:55:16.665 [760.1552] <2> SynthJob::releaseResources: finished: jmcomm_ReleaseAllResourcesForJob(SynthJob.cpp:1708)
01:55:16.665 [760.1552] <2> ACEMsgTran::close_u: input_sock_->get_handle() = 00000000000003E0(ACEMsgTran.cpp:224)
01:55:16.665 [760.1552] <2> ACEMsgTran::close_u: output_sock_->get_handle() = 00000000000003F0(ACEMsgTran.cpp:241)
01:55:16.680 [760.1552] <2> job_monitoring_exex: ACK disconnect
01:55:16.680 [760.1552] <2> job_disconnect: Disconnected
01:55:16.680 [760.1552] <2> exitSynth: exiting with status=media write error(84)(bpsynth.cpp:572)
01:55:16.680 [760.1552] <2> exitSynth: sleeping for 5000(bpsynth.cpp:575)
01:55:21.688 [760.1552] <2> exitSynth:  done sleeping for 5000(bpsynth.cpp:577)