> Currently it is unclear, what daemon closes the connection. > What you can do, is to enable debug on the different daemon: > > setdebug director level=100 trace=1 > setdebug storage=<YOURSTORAGE> level=100 trace=1 > setdebug client=<YOURCLIENT> level=100 trace=1 > > By doing this, local trace files will be generated. Maybe this gets some > further insight.
I have set the loglevel and got the trace. I have truncated the logs onto the last lines of each log. CLIENT/FILEDAEMON: ... lots of encrypt/send data lines grutto-fd (100): bfile.c:1099-10136 bopen: fname /var/backup/mysql/geheid-20171004-141001.gz, flags 0, mode 0, rdev 0 grutto-fd (20): crypto.c:488-10136 Encrypt len=65574 grutto-fd (20): crypto.c:488-10136 Encrypt len=65547 grutto-fd (20): crypto.c:488-10136 Encrypt len=29727 grutto-fd (100): heartbeat.c:151-10136 Send kill to heartbeat id grutto-fd (100): backup.c:183-10136 end blast_data ok=1 grutto-fd (100): dir_cmd.c:509-10136 Quit command loop. Canceled=0 grutto-fd (100): htable.c:84-10136 free malloc buf=7fc265259038 Pool Maxsize Maxused Inuse NoPool 65600 4 1 NAME 256 3 0 FNAME 2304 23 2 MSG 512 7 1 EMSG 1024 2 0 BSOCK 65636 4 0 RECORD 128 0 0 grutto-fd (100): htable.c:590-10136 Done destroy. grutto-fd (100): jcr.c:184-10136 write_last_jobs seek to 192 grutto-fd (100): dir_cmd.c:568-0 Done with free_jcr DIRECTOR: ...lots of lines with put_file_into_catalog albatros-dir (100): sql_create.c:922-10136 put_file_into_catalog albatros-dir (100): sql_create.c:921-10136 Fname=/var/backup/mysql/ albatros-dir (100): sql_create.c:922-10136 put_file_into_catalog albatros-dir (100): sql_create.c:921-10136 Fname=/var/backup/ albatros-dir (100): sql_create.c:922-10136 put_file_into_catalog albatros-dir (100): sql_create.c:921-10136 Fname=/var/ albatros-dir (100): sql_create.c:922-10136 put_file_into_catalog albatros-dir (100): sql_create.c:921-10136 Fname=/lib albatros-dir (100): sql_create.c:922-10136 put_file_into_catalog albatros-dir (100): backup.c:661-10136 FDStatus=T albatros-dir (100): catreq.c:124-10136 catreq CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 CreateJobMedia FirstIndex=76209 LastIndex=125990 StartFile=16 EndFile=16 StartBlock=3219625551 EndBlock=4016355046 Copy=0 Strip=0 MediaId=11 albatros-dir (100): catreq.c:124-10136 catreq CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 UpdateMedia VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1127480 VolBytes=72735831783 VolMounts=239 VolErrors=0 VolWrites=25122856 MaxVolBytes=0 EndTime=1507536564 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8216779005 VolFirstWritten=0 albatros-dir (40): catreq.c:289-10136 Update StorageId old=1 new=1 albatros-dir (100): sql_update.c:322-10136 update_media: FirstWritten=1507496730 albatros-dir (50): next_vol.c:283-10136 Vol=Wekelijks0011 expired=0 albatros-dir (100): catreq.c:97-10136 Vol Info for grutto_-_regulier.2017-10-09_09.09.37_07: 1000 OK VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1127480 VolBytes=72735831783 VolMounts=239 VolErrors=0 VolWrites=25122856 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8216779005 EndFile=16 EndBlock=4016355046 LabelType=0 MediaId=11 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 albatros-dir (100): sql_create.c:921-10136 Fname=/ albatros-dir (100): sql_create.c:922-10136 put_file_into_catalog albatros-dir (100): msgchan.c:401-10136 === End msg_thread. JobId=10136 usecnt=2 albatros-dir (100): backup.c:690-10136 cancel=1 fd_ok=1 FDJS=84 JS=102 SDJS=84 albatros-dir (100): backup.c:693-10136 fd_ok=1 FDJS=84 JS=102 SDJS=84 albatros-dir (100): storage.c:145-10136 wstorage=File albatros-dir (100): storage.c:154-10136 wstore=File where=Job resource albatros-dir (100): job.c:1459-10136 JobId=0 created Job=*JobCancel*.2017-10-09_10.11.25_08 albatros-dir (50): storage.c:181-10136 wstore=File where= albatros-dir (100): sd_cmds.c:114-10136 bnet_connect to Storage daemon albatros.xs4all.nl:9103 albatros-dir (100): bsock.c:70-10136 All source addresses albatros-dir (100): bsock_tcp.c:230-10136 Current host[ipv4;192.168.178.5;9103] All host[ipv4;192.168.178.5;9103] albatros-dir (100): bsock_tcp.c:153-10136 who=Storage daemon host=albatros.xs4all.nl port=9103 albatros-dir (100): cram-md5.c:123-10136 cram-get received: auth cram-md5 <1041461730.1507536685@albatros-sd> ssl=0 albatros-dir (99): cram-md5.c:143-10136 sending resp to challenge: 05/aj/ZyTVNsBx+Py8dc3B albatros-dir (50): cram-md5.c:75-10136 send: auth cram-md5 <824056787.1507536685@albatros-dir> ssl=0 albatros-dir (50): cram-md5.c:94-10136 Authenticate OK b4/zJicaH0+3d5UM+gVnTD albatros-dir (100): backup.c:745-10136 Enter backup_cleanup 69 E albatros-dir (100): backup.c:807-10136 Leave backup_cleanup() albatros-dir (50): job.c:616-10136 ======== End Job stat=f ========== albatros-dir (50): jobq.c:1035-0 Dec Wstore=File wncj=0 albatros-dir (50): jobq.c:925-0 Dec Client=grutto rncj=0 albatros-dir (50): jobq.c:952-0 Dec Job=grutto - regulier rncj=0 albatros-dir (100): bsys.c:78-0 safe_unlink unlinking: /var/lib/bareos/albatros-dir.grutto_-_regulier.2017-10-09_09.09.37_07.1879084648.mail albatros-dir (100): jcr.c:184-0 write_last_jobs seek to 192 STORAGE: ...other lines albatros-sd (50): askdir.c:366-10136 Update cat VolBytes=70939166328 albatros-sd (50): askdir.c:391-10136 >dird CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 UpdateMedia VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1099629 VolBytes=70939166328 VolMounts=239 VolErrors=0 VolWrites=25095005 MaxVolBytes=0 EndTime=1507536104 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8209926874 VolFirstWritten=0 albatros-sd (50): askdir.c:177-10136 <dird 1000 OK VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1099629 VolBytes=70939166328 VolMounts=239 VolErrors=0 VolWrites=25095005 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8209926874 EndFile=16 EndBlock=2219689591 LabelType=0 MediaId=11 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 albatros-sd (50): askdir.c:213-10136 do_get_volume_info return true slot=0 Volume=Wekelijks0011, VolminBlocksize=0 VolMaxBlocksize=0 albatros-sd (50): askdir.c:215-10136 setting dcr->VolMinBlocksize(0) to vol.VolMinBlocksize(0) albatros-sd (50): askdir.c:217-10136 setting dcr->VolMaxBlocksize(0) to vol.VolMaxBlocksize(0) albatros-sd (100): block.c:900-10136 dir_update_volume_info max file size -- OK albatros-sd (50): askdir.c:460-10136 >dird CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 CreateJobMedia FirstIndex=67223 LastIndex=76209 StartFile=16 EndFile=16 StartBlock=2219689592 EndBlock=3219625550 Copy=0 Strip=0 MediaId=11 albatros-sd (50): askdir.c:468-10136 <dird 1000 OK CreateJobMedia albatros-sd (50): askdir.c:366-10136 Update cat VolBytes=71939102287 albatros-sd (50): askdir.c:391-10136 >dird CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 UpdateMedia VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1115129 VolBytes=71939102287 VolMounts=239 VolErrors=0 VolWrites=25110505 MaxVolBytes=0 EndTime=1507536313 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8212998697 VolFirstWritten=0 albatros-sd (50): askdir.c:177-10136 <dird 1000 OK VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1115129 VolBytes=71939102287 VolMounts=239 VolErrors=0 VolWrites=25110505 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8212998697 EndFile=16 EndBlock=3219625550 LabelType=0 MediaId=11 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 albatros-sd (50): askdir.c:213-10136 do_get_volume_info return true slot=0 Volume=Wekelijks0011, VolminBlocksize=0 VolMaxBlocksize=0 albatros-sd (50): askdir.c:215-10136 setting dcr->VolMinBlocksize(0) to vol.VolMinBlocksize(0) albatros-sd (50): askdir.c:217-10136 setting dcr->VolMaxBlocksize(0) to vol.VolMaxBlocksize(0) albatros-sd (100): block.c:900-10136 dir_update_volume_info max file size -- OK albatros-sd (90): append.c:288-10136 back from write_end_session_label() albatros-sd (100): acquire.c:507-10136 release_device device "albatros-fs" (/shares/bareos-store) is disk albatros-sd (100): acquire.c:532-10136 There are 0 writers in release_device albatros-sd (50): askdir.c:460-10136 >dird CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 CreateJobMedia FirstIndex=76209 LastIndex=125990 StartFile=16 EndFile=16 StartBlock=3219625551 EndBlock=4016355046 Copy=0 Strip=0 MediaId=11 albatros-sd (50): askdir.c:468-10136 <dird 1000 OK CreateJobMedia albatros-sd (50): askdir.c:366-10136 Update cat VolBytes=72735831783 albatros-sd (50): askdir.c:391-10136 >dird CatReq Job=grutto_-_regulier.2017-10-09_09.09.37_07 UpdateMedia VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1127480 VolBytes=72735831783 VolMounts=239 VolErrors=0 VolWrites=25122856 MaxVolBytes=0 EndTime=1507536564 VolStatus=Append Slot=0 relabel=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8216779005 VolFirstWritten=0 albatros-sd (50): askdir.c:177-10136 <dird 1000 OK VolName=Wekelijks0011 VolJobs=6 VolFiles=16 VolBlocks=1127480 VolBytes=72735831783 VolMounts=239 VolErrors=0 VolWrites=25122856 MaxVolBytes=0 VolCapacityBytes=0 VolStatus=Append Slot=0 MaxVolJobs=0 MaxVolFiles=0 InChanger=0 VolReadTime=14942358 VolWriteTime=8216779005 EndFile=16 EndBlock=4016355046 LabelType=0 MediaId=11 EncryptionKey= MinBlocksize=0 MaxBlocksize=0 albatros-sd (50): askdir.c:213-10136 do_get_volume_info return true slot=0 Volume=Wekelijks0011, VolminBlocksize=0 VolMaxBlocksize=0 albatros-sd (50): askdir.c:215-10136 setting dcr->VolMinBlocksize(0) to vol.VolMinBlocksize(0) albatros-sd (50): askdir.c:217-10136 setting dcr->VolMaxBlocksize(0) to vol.VolMaxBlocksize(0) albatros-sd (100): acquire.c:572-10136 0 writers, 0 reserve, dev="albatros-fs" (/shares/bareos-store) albatros-sd (100): dev.c:946-10136 close_dev "albatros-fs" (/shares/bareos-store) albatros-sd (100): dev.c:1061-10136 Enter unmount albatros-sd (100): dev.c:934-10136 Clear volhdr vol=Wekelijks0011 albatros-sd (100): acquire.c:630-10136 JobId=10136 broadcast wait_device_release at 09-Oct-2017 10:09:24 albatros-sd (100): acquire.c:652-10136 Device "albatros-fs" (/shares/bareos-store) released by JobId=10136 albatros-sd (100): spool.c:729-10136 Commit attributes at 09-Oct-2017 10:09:24 albatros-sd (100): append.c:343-10136 return from do_append_data() ok=1 albatros-sd (100): jcr.c:184-10136 write_last_jobs seek to 192 albatros-sd (50): cram-md5.c:68-0 send: auth cram-md5 <1041461730.1507536685@albatros-sd> ssl=0 albatros-sd (100): cram-md5.c:123-0 cram-get received: auth cram-md5 <824056787.1507536685@albatros-dir> ssl=0 albatros-sd (99): cram-md5.c:143-0 sending resp to challenge: b4/zJicaH0+3d5UM+gVnTD albatros-sd (90): dir_cmd.c:282-0 Message channel init completed. To be sure: this job has ended with an error: 09-Oct 10:11 albatros-dir JobId 10136: Fatal error: Network error with FD during Backup: ERR=Connection reset by peer If you need more of these logs, then let me know. Hope you can do something with it. Best regards, Jurgen. -- You received this message because you are subscribed to the Google Groups "bareos-users" group. To unsubscribe from this group and stop receiving emails from it, send an email to [email protected]. To post to this group, send email to [email protected]. For more options, visit https://groups.google.com/d/optout.
