2012-01-24 21:43:45,980 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:46:09,792 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:46:54,307 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:47:08,088 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:47:47,088 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:49:23,384 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:50:19,399 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 21:53:00,508 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 22:08:03,614 INFO [Config]: Props file update detected, reloading properties from disk! 2012-01-24 22:08:19,130 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-24 22:35:07,301 INFO [Config]: Props file update detected, reloading properties from disk! 2012-01-24 22:35:07,301 INFO [Config]: Agent properties file updated via network socket! 2012-01-24 22:35:07,301 INFO [Config]: Props file update detected, reloading properties from disk! 2012-01-24 22:36:24,755 INFO [Exe]: Received task 1 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:36:24,864 INFO [192_168_1_5-23347-1]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:36:32,442 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:36:32,505 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:36:32,505 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-1 2012-01-24 22:36:34,301 INFO [Exe]: Received task 1 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:36:34,301 INFO [192_168_1_5-23347-1]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:36:40,692 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:36:40,755 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:36:40,755 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-1 2012-01-24 22:37:04,208 INFO [Exe]: Received task 1 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:37:04,208 INFO [192_168_1_5-23347-1]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:37:06,411 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:37:06,458 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:37:06,458 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-1 2012-01-24 22:37:34,223 INFO [Exe]: Received task 1 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:37:34,223 INFO [192_168_1_5-23347-1]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:37:36,567 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:37:36,614 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:37:36,614 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-1 2012-01-24 22:38:04,255 INFO [Exe]: Received task 1 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:38:04,255 INFO [192_168_1_5-23347-1]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:38:07,614 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:38:07,661 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:38:07,661 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-1 2012-01-24 22:38:31,426 ERROR [ProcessRunner]: Unable to kill 'SJQ4Task-192.168.1.5-23347-1'; {} 2012-01-24 22:38:34,239 INFO [Exe]: Received task 1 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:38:34,239 INFO [192_168_1_5-23347-1]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:38:36,833 WARN [ProcessRunner]: Killing task thread 'SJQ4Task-192.168.1.5-23347-1' 2012-01-24 22:38:36,911 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:38:36,942 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:38:36,942 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-1 2012-01-24 22:39:33,911 INFO [Config]: Props file update detected, reloading properties from disk! 2012-01-24 22:39:33,911 INFO [Config]: Agent properties file updated via network socket! 2012-01-24 22:39:33,911 INFO [Config]: Props file update detected, reloading properties from disk! 2012-01-24 22:46:04,255 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:46:04,255 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:46:09,864 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:46:09,911 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:46:09,911 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:46:34,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:46:34,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:46:36,755 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:46:36,801 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:46:36,801 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:47:04,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:47:04,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:47:06,630 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:47:06,692 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:47:06,692 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:47:34,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:47:34,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:47:36,551 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:47:36,598 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:47:36,598 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:48:04,239 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:48:04,239 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:48:06,708 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:48:06,770 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:48:06,770 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:48:34,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:48:34,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:48:36,942 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:48:37,005 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:48:37,005 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:49:04,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:49:04,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:49:06,739 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:49:06,801 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:49:06,801 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:49:34,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:49:34,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:49:36,848 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:49:36,911 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:49:36,911 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:50:04,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:50:04,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:50:06,567 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:50:06,598 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:50:06,598 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:50:34,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:50:34,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:50:36,801 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:50:36,848 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:50:36,848 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:51:04,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:51:04,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:51:06,801 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:51:06,848 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:51:06,864 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:51:34,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:51:34,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:51:36,661 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:51:36,708 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:51:36,708 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:52:04,239 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:52:04,239 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:52:06,770 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:52:06,817 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:52:06,817 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:52:34,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:52:34,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:52:36,723 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:52:36,786 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:52:36,786 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:53:04,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:53:04,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:53:06,473 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:53:06,536 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:53:06,536 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:53:34,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:53:34,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:53:36,630 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:53:36,676 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:53:36,676 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:54:04,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:54:04,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:54:06,473 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:54:06,536 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:54:06,536 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:54:34,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:54:34,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:54:36,489 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:54:36,536 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:54:36,536 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:55:04,223 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:55:04,223 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:55:06,489 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:55:06,536 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:55:06,536 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:55:34,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:55:34,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:55:36,567 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:55:36,614 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:55:36,614 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:56:04,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:56:04,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:56:06,567 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:56:06,598 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:56:06,614 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:56:34,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:56:34,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:56:36,411 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:56:36,442 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:56:36,442 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:57:04,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:57:04,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:57:06,364 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:57:06,411 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:57:06,411 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:57:34,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:57:34,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:57:36,630 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:57:36,676 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:57:36,676 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:58:04,255 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:58:04,255 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:58:07,036 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:58:07,067 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:58:07,067 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:58:34,239 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:58:34,239 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:58:36,536 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:58:36,583 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:58:36,583 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:59:04,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:59:04,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:59:06,411 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:59:06,442 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:59:06,442 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 22:59:34,192 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 22:59:34,192 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 22:59:36,598 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:59:36,661 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 22:59:36,661 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-24 23:00:04,208 INFO [Exe]: Received task 2 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-24 23:00:04,208 INFO [192_168_1_5-23347-2]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-24 23:00:06,551 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 23:00:06,614 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 23:00:06,645 ERROR [192_168_1_5-23347-2]: IOError java.io.IOException: Cannot run program "c:\scripts\full_backup.groovy": CreateProcess error=193, %1 is not a valid Win32 application at java.lang.ProcessBuilder.start(Unknown Source) at java.lang.Runtime.exec(Unknown Source) at org.apache.commons.exec.launcher.Java13CommandLauncher.exec(Java13CommandLauncher.java:58) at org.apache.commons.exec.DefaultExecutor.launch(DefaultExecutor.java:246) at org.apache.commons.exec.DefaultExecutor.executeInternal(DefaultExecutor.java:302) at org.apache.commons.exec.DefaultExecutor.execute(DefaultExecutor.java:149) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.runExternalExe(ProcessRunner.java:317) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.runExe(ProcessRunner.java:206) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.run(ProcessRunner.java:168) at java.lang.Thread.run(Unknown Source) Caused by: java.io.IOException: CreateProcess error=193, %1 is not a valid Win32 application at java.lang.ProcessImpl.create(Native Method) at java.lang.ProcessImpl.(Unknown Source) at java.lang.ProcessImpl.start(Unknown Source) ... 10 more 2012-01-24 23:00:06,723 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-24 23:00:06,723 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-2 2012-01-25 17:38:49,973 INFO [Config]: Props file update detected, reloading properties from disk! 2012-01-25 21:10:43,595 INFO [Exe]: Received task 3 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-25 21:10:43,735 INFO [192_168_1_5-23347-3]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=script:c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-25 21:10:48,735 WARN [ProcessRunner]: isActive(SJQ4Task-192.168.1.5-23347-3) = true; [SJQ4Task-192.168.1.5-23347-3] 2012-01-25 21:10:56,095 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 21:10:56,126 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 21:10:59,782 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 21:10:59,845 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 21:10:59,845 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-3 2012-01-25 21:15:51,718 INFO [Config]: Watching 'C:\Program Files\SageTV\SageTV\sjqagent\bin\..\conf' 2012-01-25 21:22:07,102 INFO [Exe]: Received task 35 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-25 21:22:07,118 INFO [192_168_1_5-23347-35]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=script:c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-25 21:22:12,821 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 21:22:12,836 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 21:28:46,733 ERROR [192_168_1_5-23347-35]: Failed to send task output to server! java.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(Unknown Source) at java.net.PlainSocketImpl.connectToAddress(Unknown Source) at java.net.PlainSocketImpl.connect(Unknown Source) at java.net.SocksSocketImpl.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.(Unknown Source) at java.net.Socket.(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.ListenerClient.(ListenerClient.java:41) at com.google.code.sagetvaddons.sjq.agent.network.ServerClient.(ServerClient.java:30) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.runExe(ProcessRunner.java:212) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.run(ProcessRunner.java:168) at java.lang.Thread.run(Unknown Source) 2012-01-25 21:28:47,827 FATAL [192_168_1_5-23347-35]: Failed to update Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=script:c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] java.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(Unknown Source) at java.net.PlainSocketImpl.connectToAddress(Unknown Source) at java.net.PlainSocketImpl.connect(Unknown Source) at java.net.SocksSocketImpl.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.(Unknown Source) at java.net.Socket.(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.ListenerClient.(ListenerClient.java:41) at com.google.code.sagetvaddons.sjq.agent.network.ServerClient.(ServerClient.java:30) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.updateTask(ProcessRunner.java:226) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.run(ProcessRunner.java:173) at java.lang.Thread.run(Unknown Source) 2012-01-25 21:28:47,827 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-35 2012-01-25 21:43:16,781 WARN [ProcessRunner]: isActive(SJQ4Task-192.168.1.5-23347-35) = false; [] 2012-01-25 23:43:58,240 INFO [Exe]: Received task 36 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-25 23:43:58,240 INFO [192_168_1_5-23347-36]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=script:c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-25 23:44:03,943 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 23:44:03,943 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 23:44:06,130 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 23:44:06,161 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 23:44:06,161 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-36 2012-01-25 23:48:39,859 INFO [Exe]: Received task 37 of type 'FULLBACKUP' from 192.168.1.5:23347... 2012-01-25 23:48:39,859 INFO [192_168_1_5-23347-37]: Starting process runner for: Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=script:c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] 2012-01-25 23:48:42,047 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 23:48:42,047 INFO [ListenerClient]: Disconnected from 192.168.1.5:23347 2012-01-25 23:54:48,852 ERROR [192_168_1_5-23347-37]: Failed to send task output to server! java.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(Unknown Source) at java.net.PlainSocketImpl.connectToAddress(Unknown Source) at java.net.PlainSocketImpl.connect(Unknown Source) at java.net.SocksSocketImpl.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.(Unknown Source) at java.net.Socket.(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.ListenerClient.(ListenerClient.java:41) at com.google.code.sagetvaddons.sjq.agent.network.ServerClient.(ServerClient.java:30) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.runExe(ProcessRunner.java:212) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.run(ProcessRunner.java:168) at java.lang.Thread.run(Unknown Source) 2012-01-25 23:54:49,946 FATAL [192_168_1_5-23347-37]: Failed to update Task[id=FULLBACKUP, reqRes=100, maxInst=1, sched=ON, maxTime=86400, maxTimeRatio=1.0, rc=0-0, exe=script:c:\scripts\full_backup.groovy, exeArgs=, test=c:\scripts\full_backup_test.groovy, testArgs=] java.net.ConnectException: Connection refused: connect at java.net.PlainSocketImpl.socketConnect(Native Method) at java.net.PlainSocketImpl.doConnect(Unknown Source) at java.net.PlainSocketImpl.connectToAddress(Unknown Source) at java.net.PlainSocketImpl.connect(Unknown Source) at java.net.SocksSocketImpl.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.connect(Unknown Source) at java.net.Socket.(Unknown Source) at java.net.Socket.(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.ListenerClient.(ListenerClient.java:41) at com.google.code.sagetvaddons.sjq.agent.network.ServerClient.(ServerClient.java:30) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.updateTask(ProcessRunner.java:226) at com.google.code.sagetvaddons.sjq.agent.ProcessRunner.run(ProcessRunner.java:173) at java.lang.Thread.run(Unknown Source) 2012-01-25 23:54:49,946 WARN [ProcessRunner]: Removing task from active list: SJQ4Task-192.168.1.5-23347-37