bareos-dir: dird.c:194-0 Debug level = 200
bareos-dir: inc_conf.c:379-0 set wildfile ee61f8 size=1 [A-Z]:/pagefile.sys
bareos-dir: inc_conf.c:379-0 set wilddir ee61f8 size=1 [A-Z]:/RECYCLER
bareos-dir: inc_conf.c:379-0 set wilddir ee61f8 size=2 [A-Z]:/$RECYCLE.BIN
bareos-dir: inc_conf.c:379-0 set wilddir ee61f8 size=3 [A-Z]:/System Volume Information
bareos-dir: dird_conf.c:3038-0 store_runscript: begin store_runscript pass=1
bareos-dir: dird_conf.c:3038-0 store_runscript: begin store_runscript pass=1
bareos-dir: runscript.c:334-0 runscript: debug
bareos-dir: runscript.c:335-0  --> RunScript
bareos-dir: runscript.c:336-0   --> Command=/usr/lib/bareos/scripts/make_catalog_backup.pl MyCatalog
bareos-dir: runscript.c:337-0   --> Target=
bareos-dir: runscript.c:338-0   --> RunOnSuccess=1
bareos-dir: runscript.c:339-0   --> RunOnFailure=0
bareos-dir: runscript.c:340-0   --> FailJobOnError=1
bareos-dir: runscript.c:341-0   --> RunWhen=2
bareos-dir: runscript.c:334-0 runscript: debug
bareos-dir: runscript.c:335-0  --> RunScript
bareos-dir: runscript.c:336-0   --> Command=/usr/lib/bareos/scripts/delete_catalog_backup
bareos-dir: runscript.c:337-0   --> Target=
bareos-dir: runscript.c:338-0   --> RunOnSuccess=1
bareos-dir: runscript.c:339-0   --> RunOnFailure=0
bareos-dir: runscript.c:340-0   --> FailJobOnError=0
bareos-dir: runscript.c:341-0   --> RunWhen=1
bareos-dir: dird_conf.c:3038-0 store_runscript: begin store_runscript pass=2
bareos-dir: dird_conf.c:2944-0 runscript cmd=/usr/local/bin/bareos_test_adminjob.sh type=|
bareos-dir: runscript.c:334-0 runscript: debug
bareos-dir: runscript.c:335-0  --> RunScript
bareos-dir: runscript.c:336-0   --> Command=/usr/local/bin/bareos_test_adminjob.sh
bareos-dir: runscript.c:337-0   --> Target=%c
bareos-dir: runscript.c:338-0   --> RunOnSuccess=1
bareos-dir: runscript.c:339-0   --> RunOnFailure=0
bareos-dir: runscript.c:340-0   --> FailJobOnError=1
bareos-dir: runscript.c:341-0   --> RunWhen=2
bareos-dir: dird_conf.c:3038-0 store_runscript: begin store_runscript pass=2
bareos-dir: dird_conf.c:2944-0 runscript cmd=/usr/local/bin/bareos_test_adminjob.sh type=|
bareos-dir: runscript.c:334-0 runscript: debug
bareos-dir: runscript.c:335-0  --> RunScript
bareos-dir: runscript.c:336-0   --> Command=/usr/local/bin/bareos_test_adminjob.sh
bareos-dir: runscript.c:337-0   --> Target=%c
bareos-dir: runscript.c:338-0   --> RunOnSuccess=1
bareos-dir: runscript.c:339-0   --> RunOnFailure=0
bareos-dir: runscript.c:340-0   --> FailJobOnError=1
bareos-dir: runscript.c:341-0   --> RunWhen=2
bareos-dir: jcr.c:141-0 read_last_jobs seek to 192
bareos-dir: jcr.c:148-0 Read num_items=9
bareos-dir: dird.c:317-0 backend path: /usr/lib64/bareos/backends
bareos-dir: dir_plugins.c:205-0 Load dir plugins
bareos-dir: dir_plugins.c:207-0 No dir plugin dir!
bareos-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename dbi, partly_compare = true
bareos-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename mysql, partly_compare = false
bareos-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename postgresql, partly_compare = false
bareos-dir: cats_backends.c:157-0 db_init_database: testing backend /usr/lib64/bareos/backends/libbareoscats-postgresql.so
bareos-dir: cats_backends.c:222-0 db_init_database: loaded backend /usr/lib64/bareos/backends/libbareoscats-postgresql.so
bareos-dir: postgresql.c:1173-0 db_init_database first time
bareos-dir: postgresql.c:231-0 pg_real_connect done
bareos-dir: postgresql.c:233-0 db_user=bareos db_name=bareos db_password=qU1.ab4-9i2.9e2
bareos-dir: sql_create.c:387-0 In create mediatype
bareos-dir: sql_create.c:391-0 selectmediatype: SELECT MediaTypeId,MediaType FROM MediaType WHERE MediaType='File'
bareost01-dir: dird.c:356-0 Start UA server
bareost01-dir: job.c:1715-0 wstorage=File
bareost01-dir: job.c:1724-0 wstore=File where=Job resource
bareost01-dir: bnet_server_tcp.c:166-0 Addresses host[ipv4;0.0.0.0;9101] 
bareost01-dir: job.c:1352-0 JobId=0 created Job=*JobMonitor*.2015-02-01_13.13.49_01
bareost01-dir: dird.c:373-0 wait for next job
bareost01-dir: scheduler.c:98-0 Enter wait_for_next_job
bareost01-dir: scheduler.c:322-0 enter find_runs()
bareost01-dir: scheduler.c:338-0 now = 54ce187d: h=13 m=1 md=0 wd=0 wom=0 woy=5 yday=31
bareost01-dir: scheduler.c:358-0 nh = 54ce268d: h=14 m=1 md=0 wd=0 wom=0 woy=5 yday=31

bareost01-dir: scheduler.c:375-0 Got job: BackupClient1
bareost01-dir: scheduler.c:420-0 run@edf2b8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: BackupCatalog
bareost01-dir: scheduler.c:420-0 run@edf108: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: Backup_test02
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: test_PyLocalFileset_Job
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: test_PyLocalFilesetTest_Job
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: test_OptionPyLocalFileset_Job
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: test_PyOptionInteract_Job
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: test_PyMySQL_Job
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: test_bpipe_bt01_Job
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: myArchTest
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: myArchTest1
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: myArchTest2
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: myArchTest3
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: TestAdminJobOnClient
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:375-0 Got job: TestJobWithScriptOnClient
bareost01-dir: scheduler.c:420-0 run@edebf8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@ededa8: run_now=0 run_nh=0
bareost01-dir: scheduler.c:420-0 run@edef58: run_now=0 run_nh=0
bareost01-dir: scheduler.c:439-0 Leave find_runs()
bareost01-dir: job.c:1715-0 wstorage=File
bareost01-dir: job.c:1724-0 wstore=File where=Job resource
bareost01-dir: job.c:1352-0 JobId=0 created Job=*StatisticsCollector*.2015-02-01_13.13.49_02
bareost01-dir: sql_pooling.c:58-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type postgresql
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename dbi, partly_compare = true
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename mysql, partly_compare = false
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename postgresql, partly_compare = false
bareost01-dir: postgresql.c:1173-0 db_init_database first time
bareost01-dir: postgresql.c:231-0 pg_real_connect done
bareost01-dir: postgresql.c:233-0 db_user=bareos db_name=bareos db_password=qU1.ba1-9i2.9e2
bareost01-dir: stats.c:164-0 statistics_thread_runner: Doing work at 1422792829
bareost01-dir: stats.c:178-0 statistics_thread_runner: flushing pending statistics
bareost01-dir: job.c:1715-0 wstorage=File
bareost01-dir: job.c:1724-0 wstore=File where=Job resource
bareost01-dir: job.c:1352-0 JobId=0 created Job=-Console-.2015-02-01_13.13.52_03
bareost01-dir: cram-md5.c:68-0 send: auth cram-md5 <562062386.1422792832@bareost01-dir> ssl=0
bareost01-dir: cram-md5.c:123-0 cram-get received: auth cram-md5 <432567627.1422792832@bconsole> ssl=0
bareost01-dir: cram-md5.c:143-0 sending resp to challenge: V+120H+TMkF5s3lmaTZsmD
bareost01-dir: ua_cmds.c:2549-0 UA Open database
bareost01-dir: sql_pooling.c:58-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type postgresql
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename dbi, partly_compare = true
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename mysql, partly_compare = false
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename postgresql, partly_compare = false
bareost01-dir: postgresql.c:1167-0 DB REopen bareos
bareost01-dir: ua_cmds.c:2580-0 DB bareos opened
bareost01-dir: ua_run.c:1954-0 Using pool File
bareost01-dir: job.c:1715-0 wstorage=File
bareost01-dir: job.c:1724-0 wstore=File where=Job resource
bareost01-dir: job.c:1748-0 wstore=File where=Job resource
bareost01-dir: ua_run.c:431-0 JobId=0 using pool File priority=40
bareost01-dir: job.c:1352-0 JobId=0 created Job=TestAdminJobOnClient.2015-02-01_13.14.00_04
bareost01-dir: job.c:141-0 Open database
bareost01-dir: sql_pooling.c:58-0 db_sql_get_non_pooled_connection allocating 1 new non pooled database connection to database bareos, backend type postgresql
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename dbi, partly_compare = true
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename mysql, partly_compare = false
bareost01-dir: cats_backends.c:63-0 db_init_database: Trying to find mapping of given interfacename postgresql to mapping interfacename postgresql, partly_compare = false
bareost01-dir: postgresql.c:1167-0 DB REopen bareos
bareost01-dir: job.c:156-0 DB opened
bareost01-dir: job.c:1222-0 Created Client bareost01-fd record 1
bareost01-dir: job.c:196-0 Created job record JobId=263 Name=TestAdminJobOnClient.2015-02-01_13.14.00_04 Type=D Level=F
bareost01-dir: dir_plugins.c:427-0 === enter new_plugins ===
bareost01-dir: dir_plugins.c:429-0 No dir plugin list!
bareost01-dir: job.c:81-0 Add jrc to work queue
bareost01-dir: ua_run.c:437-0 JobId=263 NewJobId=263 using pool File priority=40
bareost01-dir: jobq.c:821-0 Wstore=File
bareost01-dir: jobq.c:824-0 Inc wncj=1
bareost01-dir: job.c:342-263 =====Start Job=========
bareost01-dir: runscript.c:149-263 runscript: running all RUNSCRIPT object (BeforeJob) JobStatus=R
bareost01-dir: runscript.c:165-263 runscript: try to run %c:/usr/local/bin/bareos_test_adminjob.sh
bareost01-dir: runscript.c:172-263 runscript: Run it because SCRIPT_Before (/usr/local/bin/bareos_test_adminjob.sh,1,0,R)
bareost01-dir: admin.c:72-263 Enter backup_cleanup()
bareost01-dir: admin.c:119-263 Leave admin_cleanup()
bareost01-dir: runscript.c:149-263 runscript: running all RUNSCRIPT object (AfterJob) JobStatus=T
bareost01-dir: runscript.c:165-263 runscript: try to run %c:/usr/local/bin/bareos_test_adminjob.sh
bareost01-dir: job.c:519-263 ======== End Job stat=T ==========
bareost01-dir: jobq.c:465-0 Done lock mutex after running job. Release locks.
bareost01-dir: jobq.c:937-0 Dec wncj=0
bareost01-dir: util.c:768-0 edit_job_codes: /usr/sbin/bsmtp -h localhost -f "(Bareos) <%r>" -s "Bareos: %t %e of %c %l" %r
bareost01-dir: bsys.c:73-0 safe_unlink unlinking: /var/lib/bareos/bareost01-dir.TestAdminJobOnClient.2015-02-01_13.14.00_04.335566568.mail
bareost01-dir: job.c:1391-0 Start dird free_jcr
bareost01-dir: jcr.c:184-0 write_last_jobs seek to 192
bareost01-dir: job.c:1451-0 End dird free_jcr
bareost01-dir: jobq.c:639-0 unlock mutex