08-Oct-2017 07:57:08.939774 windwalker-dir (110): socket_server.c:87-0 Conn: Hello *UserAgent* calling 08-Oct-2017 07:57:09.340019 windwalker-dir (100): storage.c:146-0 wstorage=vchanger_8tb 08-Oct-2017 07:57:09.340059 windwalker-dir (100): storage.c:146-0 wstorage=vchanger_3tb 08-Oct-2017 07:57:09.340091 windwalker-dir (100): storage.c:155-0 wstore=vchanger_8tb where=Job resource 08-Oct-2017 07:57:09.340130 windwalker-dir (100): job.c:1484-0 JobId=0 created Job=-Console-.2017-10-08_07.57.09_09 08-Oct-2017 07:57:09.340362 windwalker-dir (50): cram-md5.c:68-0 send: auth cram-md5 <1126551502.1507471029@windwalker-dir> ssl=0 08-Oct-2017 07:57:09.340602 windwalker-dir (100): cram-md5.c:123-0 cram-get received: auth cram-md5 <875463446.1507471029@bconsole> ssl=0 08-Oct-2017 07:57:09.340661 windwalker-dir (99): cram-md5.c:143-0 sending resp to challenge: RyE2SnQ/RSA3likE67+zBC 08-Oct-2017 07:57:09.341638 windwalker-dir (10): ua_audit.c:143-0 : Console [default] from [192.168.9.1] cmdline update slots storage=vchanger_monthly_1.5tb 08-Oct-2017 07:57:09.413249 windwalker-dir (100): ua_db.c:150-0 UA Open database 08-Oct-2017 07:57:09.413325 windwalker-dir (100): sql_pooling.c:60-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type mysql 08-Oct-2017 07:57:09.413385 windwalker-dir (100): cats_backends.c:65-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename dbi, partly_compare = true 08-Oct-2017 07:57:09.413423 windwalker-dir (100): cats_backends.c:65-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename mysql, partly_compare = false 08-Oct-2017 07:57:09.413489 windwalker-dir (100): mysql.c:857-0 DB REopen bareos 08-Oct-2017 07:57:09.413577 windwalker-dir (150): ua_db.c:183-0 DB bareos opened 08-Oct-2017 07:57:09.413625 windwalker-dir (100): ua_select.c:1166-0 get_storage_resource: autochangers_only is 1 08-Oct-2017 07:57:09.413685 windwalker-dir (50): storage.c:182-0 wstore=vchanger_monthly_1.5tb where=command line 08-Oct-2017 07:57:09.413757 windwalker-dir (100): ua_update.c:1114-0 max_slots=26 08-Oct-2017 07:57:09.413798 windwalker-dir (100): ua_select.c:1811-0 Slots turned on: 08-Oct-2017 07:57:09.413841 windwalker-dir (100): ua_select.c:1814-0 1 08-Oct-2017 07:57:09.413875 windwalker-dir (100): ua_select.c:1814-0 2 08-Oct-2017 07:57:09.413906 windwalker-dir (100): ua_select.c:1814-0 3 08-Oct-2017 07:57:09.413935 windwalker-dir (100): ua_select.c:1814-0 4 08-Oct-2017 07:57:09.413978 windwalker-dir (100): ua_select.c:1814-0 5 08-Oct-2017 07:57:09.414022 windwalker-dir (100): ua_select.c:1814-0 6 08-Oct-2017 07:57:09.414065 windwalker-dir (100): ua_select.c:1814-0 7 08-Oct-2017 07:57:09.414095 windwalker-dir (100): ua_select.c:1814-0 8 08-Oct-2017 07:57:09.414125 windwalker-dir (100): ua_select.c:1814-0 9 08-Oct-2017 07:57:09.414155 windwalker-dir (100): ua_select.c:1814-0 10 08-Oct-2017 07:57:09.414192 windwalker-dir (100): ua_select.c:1814-0 11 08-Oct-2017 07:57:09.414221 windwalker-dir (100): ua_select.c:1814-0 12 08-Oct-2017 07:57:09.414262 windwalker-dir (100): ua_select.c:1814-0 13 08-Oct-2017 07:57:09.414378 windwalker-dir (100): ua_select.c:1814-0 14 08-Oct-2017 07:57:09.414438 windwalker-dir (100): ua_select.c:1814-0 15 08-Oct-2017 07:57:09.414469 windwalker-dir (100): ua_select.c:1814-0 16 08-Oct-2017 07:57:09.414499 windwalker-dir (100): ua_select.c:1814-0 17 08-Oct-2017 07:57:09.414682 windwalker-dir (100): ua_select.c:1814-0 18 08-Oct-2017 07:57:09.414716 windwalker-dir (100): ua_select.c:1814-0 19 08-Oct-2017 07:57:09.414752 windwalker-dir (100): ua_select.c:1814-0 20 08-Oct-2017 07:57:09.414797 windwalker-dir (100): ua_select.c:1814-0 21 08-Oct-2017 07:57:09.414828 windwalker-dir (100): ua_select.c:1814-0 22 08-Oct-2017 07:57:09.414858 windwalker-dir (100): ua_select.c:1814-0 23 08-Oct-2017 07:57:09.414898 windwalker-dir (100): ua_select.c:1814-0 24 08-Oct-2017 07:57:09.414929 windwalker-dir (100): ua_select.c:1814-0 25 08-Oct-2017 07:57:09.414960 windwalker-dir (100): ua_select.c:1814-0 26 08-Oct-2017 07:57:09.414996 windwalker-dir (100): storage.c:602-0 Freeing volume list 08-Oct-2017 07:57:09.415256 windwalker-dir (100): sd_cmds.c:115-0 bnet_connect to Storage daemon windwalker:9103 08-Oct-2017 07:57:09.415319 windwalker-dir (100): bsock.c:70-0 All source addresses 08-Oct-2017 07:57:09.415870 windwalker-dir (100): bsock_tcp.c:230-0 Current host[ipv4;192.168.9.1;9103] All host[ipv4;192.168.9.1;9103] 08-Oct-2017 07:57:09.416101 windwalker-dir (100): bsock_tcp.c:153-0 who=Storage daemon host=windwalker port=9103 08-Oct-2017 07:57:09.416737 windwalker-dir (100): cram-md5.c:123-0 cram-get received: auth cram-md5 <1455413860.1507471029@windwalker-sd> ssl=0 08-Oct-2017 07:57:09.416799 windwalker-dir (99): cram-md5.c:143-0 sending resp to challenge: B6FaX0+/nj5F1z/LWX/PWA 08-Oct-2017 07:57:09.416925 windwalker-dir (50): cram-md5.c:75-0 send: auth cram-md5 <440657596.1507471029@windwalker-dir> ssl=0 08-Oct-2017 07:57:09.417078 windwalker-dir (50): cram-md5.c:94-0 Authenticate OK s0+no4+Qt58Ql5+4ml/SQC 08-Oct-2017 07:57:09.417338 windwalker-dir (116): authenticate.c:98-0 >stored: 1000 OK auth 08-Oct-2017 07:57:09.417421 windwalker-dir (110): authenticate.c:105-0 ssl=0 08-Oct-2017 07:57:10.094638 windwalker-dir (100): cram-md5.c:123-0 cram-get received: auth cram-md5 <1361969779.1507471030@bconsole> ssl=0 08-Oct-2017 07:57:10.094698 windwalker-dir (99): cram-md5.c:143-0 sending resp to challenge: 0+/r8SJGG6FF14+HiR/vKA 08-Oct-2017 07:57:10.095399 windwalker-dir (10): ua_audit.c:143-0 : Console [default] from [192.168.9.1] cmdline update volume=vchanger_monthly_1.5tb_drives_0001_0001 ActionOnPurge=Truncate 08-Oct-2017 07:57:10.095601 windwalker-dir (100): ua_db.c:150-0 UA Open database 08-Oct-2017 07:57:10.095642 windwalker-dir (100): sql_pooling.c:60-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type mysql 08-Oct-2017 07:57:10.095674 windwalker-dir (100): cats_backends.c:65-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename dbi, partly_compare = true 08-Oct-2017 07:57:10.095705 windwalker-dir (100): cats_backends.c:65-0 db_init_database: Trying to find mapping of given interfacename mysql to mapping interfacename mysql, partly_compare = false 08-Oct-2017 07:57:10.095751 windwalker-dir (100): mysql.c:857-0 DB REopen bareos 08-Oct-2017 07:57:10.095792 windwalker-dir (150): ua_db.c:183-0 DB bareos opened 08-Oct-2017 07:57:40.228952 windwalker-dir (200): scheduler.c:326-0 enter find_runs() 08-Oct-2017 07:57:40.238651 windwalker-dir (200): scheduler.c:342-0 now = 59da2ed4: h=7 m=9 md=7 wd=0 wom=1 woy=40 yday=280 08-Oct-2017 07:57:40.238869 windwalker-dir (200): scheduler.c:362-0 nh = 59da3ce4: h=8 m=9 md=7 wd=0 wom=1 woy=40 yday=280 08-Oct-2017 07:57:40.239029 windwalker-dir (200): scheduler.c:379-0 Got job: Backup_Windwalker_Daily 08-Oct-2017 07:57:40.239235 windwalker-dir (200): scheduler.c:424-0 run@1caa9b8: run_now=0 run_nh=0 08-Oct-2017 07:57:40.239362 windwalker-dir (200): scheduler.c:424-0 run@1caab68: run_now=0 run_nh=0 08-Oct-2017 07:57:40.239487 windwalker-dir (200): scheduler.c:424-0 run@1caad18: run_now=0 run_nh=0 08-Oct-2017 07:57:40.239561 windwalker-dir (200): scheduler.c:379-0 Got job: Backup_Taffy_Daily 08-Oct-2017 07:57:40.239753 windwalker-dir (200): scheduler.c:424-0 run@1caa9b8: run_now=0 run_nh=0 08-Oct-2017 07:57:40.239803 windwalker-dir (200): scheduler.c:424-0 run@1caab68: run_now=0 run_nh=0 08-Oct-2017 07:57:40.239940 windwalker-dir (200): scheduler.c:424-0 run@1caad18: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240100 windwalker-dir (200): scheduler.c:379-0 Got job: Backup_Sonny_Daily 08-Oct-2017 07:57:40.240262 windwalker-dir (200): scheduler.c:424-0 run@1caa9b8: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240373 windwalker-dir (200): scheduler.c:424-0 run@1caab68: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240430 windwalker-dir (200): scheduler.c:424-0 run@1caad18: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240463 windwalker-dir (200): scheduler.c:379-0 Got job: Backup_Samson_Daily 08-Oct-2017 07:57:40.240495 windwalker-dir (200): scheduler.c:424-0 run@1caa9b8: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240526 windwalker-dir (200): scheduler.c:424-0 run@1caab68: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240568 windwalker-dir (200): scheduler.c:424-0 run@1caad18: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240600 windwalker-dir (200): scheduler.c:379-0 Got job: Backup_MuadDib_Daily 08-Oct-2017 07:57:40.240631 windwalker-dir (200): scheduler.c:424-0 run@1caa9b8: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240662 windwalker-dir (200): scheduler.c:424-0 run@1caab68: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240692 windwalker-dir (200): scheduler.c:424-0 run@1caad18: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240737 windwalker-dir (200): scheduler.c:379-0 Got job: BackupCatalog 08-Oct-2017 07:57:40.240768 windwalker-dir (200): scheduler.c:424-0 run@1cac4b8: run_now=0 run_nh=0 08-Oct-2017 07:57:40.240800 windwalker-dir (200): scheduler.c:445-0 Leave find_runs() 08-Oct-2017 07:57:59.016036 windwalker-dir (10): ua_audit.c:143-0 : Console [default] from [192.168.9.1] cmdline setdebug director level=7 trace=0 08-Oct-2017 07:58:00.338680 windwalker-dir (120): ua_cmds.c:1305-0 setdebug:setdebug director level=7 trace=0: