18-Nov-2014 20:06:37.353 [3511182@qtp-17519204-0] INFO u.c.m.sagetv.catchup.CatchupServer - Looking for recording of ClickAustraliaSpecial 18-Nov-2014 20:06:37.353 [3511182@qtp-17519204-0] INFO u.c.m.sagetv.catchup.CatchupServer - Starting new recording of ClickAustraliaSpecial to keep 18-Nov-2014 20:06:37.355 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Starting recording of ClickAustraliaSpecial 18-Nov-2014 20:06:37.365 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Spawn cmd.exe /c "cd C:\Program Files (x86)\get_iplayer\ && get_iplayer.cmd http://www.bbc.co.uk/iplayer/episode/b04sm3sj/Click_Australia_Special/ --attempts 0 --force -o ..\SageTV\SageTV\sagetvcatchup\recordings\" 18-Nov-2014 20:06:37.373 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:37.712 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - get_iplayer v2.90, Copyright (C) 2008-2010 Phil Lewis 18-Nov-2014 20:06:37.714 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - This program comes with ABSOLUTELY NO WARRANTY; for details use --warranty. 18-Nov-2014 20:06:37.715 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - This is free software, and you are welcome to redistribute it under certain 18-Nov-2014 20:06:37.717 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - conditions; use --conditions for details. 18-Nov-2014 20:06:37.718 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - 18-Nov-2014 20:06:38.075 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Episode-only pid detected 18-Nov-2014 20:06:38.076 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Trying pid: b04sm3sj using type: tv 18-Nov-2014 20:06:38.078 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - INFO Trying to stream pid using type tv 18-Nov-2014 20:06:38.079 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: pid found in cache 18-Nov-2014 20:06:38.081 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - Matches: 18-Nov-2014 20:06:38.082 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - 377: Click - Australia Special, BBC News Channel, Factual,News,Science & Nature,Science & Technology, default 18-Nov-2014 20:06:38.084 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - 18-Nov-2014 20:06:38.085 [get_iplayer.out-7] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: 1 Matching Programmes 18-Nov-2014 20:06:38.375 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:39.376 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:40.376 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:41.378 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:42.379 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:43.379 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:44.380 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:45.381 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:46.382 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:47.382 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:48.382 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:49.382 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:50.382 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:51.383 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:52.384 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:53.384 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:54.385 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:55.386 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:56.387 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:57.387 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:58.388 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:06:59.390 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:00.391 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:01.391 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:02.392 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:03.393 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:04.394 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:05.394 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:06.395 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 20:07:07.396 [catchup-recorder-0] ERROR u.c.m.sagetv.catchup.CatchupServer - Unable to record: ClickAustraliaSpecial java.lang.Exception: get_iplayer returned no file after 30000 at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) ~[na:1.7.0_65] at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) ~[na:1.7.0_65] at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) ~[na:1.7.0_65] at java.lang.reflect.Constructor.newInstance(Unknown Source) ~[na:1.7.0_65] at org.codehaus.groovy.reflection.CachedConstructor.invoke(CachedConstructor.java:77) ~[groovy-2.3.7.jar:2.3.7] at org.codehaus.groovy.runtime.callsite.ConstructorSite$ConstructorSiteNoUnwrapNoCoerce.callConstructor(ConstructorSite.java:102) ~[groovy-2.3.7.jar:2.3.7] at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallConstructor(CallSiteArray.java:57) ~[groovy-2.3.7.jar:2.3.7] at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callConstructor(AbstractCallSite.java:182) ~[groovy-2.3.7.jar:2.3.7] at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callConstructor(AbstractCallSite.java:190) ~[groovy-2.3.7.jar:2.3.7] at Iplayer.playEpisode.run(playEpisode.groovy:23) ~[na:na] at uk.co.mdjcox.sagetv.catchup.plugins.Script.call(Script.java:69) ~[sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.plugins.PlayScript.play(PlayScript.java:32) ~[sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.plugins.Plugin.playEpisode(Plugin.java:68) [sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.Recorder.callPlayScript(Recorder.java:372) [sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.Recorder.access$000(Recorder.java:37) [sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.Recorder$1.run(Recorder.java:126) [sagetvcatchup.jar:na] at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [na:1.7.0_65] at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.7.0_65] at java.lang.Thread.run(Unknown Source) [na:1.7.0_65] 18-Nov-2014 20:07:07.398 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Returning file null for http://www.bbc.co.uk/iplayer/episode/b04sm3sj/Click_Australia_Special/ 18-Nov-2014 20:07:07.400 [catchup-recorder-0] WARN u.c.m.sagetv.catchup.CatchupServer - Recording ClickAustraliaSpecial failed due to exception java.lang.Exception: No partial recording file found at uk.co.mdjcox.sagetv.catchup.Recorder.callPlayScript(Recorder.java:383) ~[sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.Recorder.access$000(Recorder.java:37) ~[sagetvcatchup.jar:na] at uk.co.mdjcox.sagetv.catchup.Recorder$1.run(Recorder.java:126) ~[sagetvcatchup.jar:na] at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) [na:1.7.0_65] at java.util.concurrent.FutureTask.run(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [na:1.7.0_65] at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [na:1.7.0_65] at java.lang.Thread.run(Unknown Source) [na:1.7.0_65] 18-Nov-2014 20:07:07.401 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Recording of ClickAustraliaSpecial tidying up 18-Nov-2014 20:07:07.403 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Going to stop recording of ClickAustraliaSpecial 18-Nov-2014 20:07:07.404 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Stopping recording of ClickAustraliaSpecial 18-Nov-2014 20:07:07.411 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killing processes matching: .*iplayer.*b04sm3sj.* 18-Nov-2014 20:07:07.526 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killing process 5232 for perl.exe get_iplayer.pl http://www.bbc.co.uk/iplayer/episode/b04sm3sj/Click_Australia_Special/ --attempts 0 --force -o ..\SageTV\SageTV\sagetvcatchup\recordings\ 18-Nov-2014 20:07:07.576 [kill.out-9] INFO u.c.m.sagetv.catchup.CatchupServer - SUCCESS: The process with PID 5232 has been terminated. 18-Nov-2014 20:07:07.579 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killed process 5232 for perl.exe get_iplayer.pl http://www.bbc.co.uk/iplayer/episode/b04sm3sj/Click_Australia_Special/ --attempts 0 --force -o ..\SageTV\SageTV\sagetvcatchup\recordings\ 18-Nov-2014 20:07:07.581 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killing process 9356 for cmd.exe /c "cd C:\Program Files (x86)\get_iplayer\ && get_iplayer.cmd http://www.bbc.co.uk/iplayer/episode/b04sm3sj/Click_Australia_Special/ --attempts 0 --force -o ..\SageTV\SageTV\sagetvcatchup\recordings\" 18-Nov-2014 20:07:07.631 [kill.err-10] INFO u.c.m.sagetv.catchup.CatchupServer - ERROR: The process "9356" not found. 18-Nov-2014 20:07:07.633 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killed process 9356 for cmd.exe /c "cd C:\Program Files (x86)\get_iplayer\ && get_iplayer.cmd http://www.bbc.co.uk/iplayer/episode/b04sm3sj/Click_Australia_Special/ --attempts 0 --force -o ..\SageTV\SageTV\sagetvcatchup\recordings\" 18-Nov-2014 20:07:07.635 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killed processes [5232, 9356] which matched .*iplayer.*b04sm3sj.* 18-Nov-2014 20:07:07.636 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Stopped recording of ClickAustraliaSpecial 18-Nov-2014 20:07:07.638 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Deleting files for recording ClickAustraliaSpecial 18-Nov-2014 20:07:07.639 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Removing recording ClickAustraliaSpecial