18-Nov-2014 19:51:11.842 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Starting recording of ClickAustraliaSpecial 18-Nov-2014 19:51:11.861 [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 19:51:11.871 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:12.457 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - get_iplayer v2.90, Copyright (C) 2008-2010 Phil Lewis 18-Nov-2014 19:51:12.458 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - This program comes with ABSOLUTELY NO WARRANTY; for details use --warranty. 18-Nov-2014 19:51:12.460 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - This is free software, and you are welcome to redistribute it under certain 18-Nov-2014 19:51:12.461 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - conditions; use --conditions for details. 18-Nov-2014 19:51:12.462 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - 18-Nov-2014 19:51:12.873 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:13.048 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Episode-only pid detected 18-Nov-2014 19:51:13.050 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Trying pid: b04sm3sj using type: tv 18-Nov-2014 19:51:13.051 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Trying to stream pid using type tv 18-Nov-2014 19:51:13.053 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: pid not found in tv cache 18-Nov-2014 19:51:13.054 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - Matches: 18-Nov-2014 19:51:13.055 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - 18-Nov-2014 19:51:13.057 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: 1 Matching Programmes 18-Nov-2014 19:51:13.874 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:14.876 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:15.877 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:16.878 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:17.879 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:18.880 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:19.882 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:20.883 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:21.884 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:22.884 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:23.885 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:24.886 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:25.887 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:26.888 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:27.889 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:28.889 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:29.890 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:30.891 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:31.893 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:32.893 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:33.894 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:34.895 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:35.896 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:36.896 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:37.897 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:38.898 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:39.899 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:40.899 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Waiting for 'INFO: File name prefix = ' in job output 18-Nov-2014 19:51:41.269 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Checking existence of default version 18-Nov-2014 19:51:41.271 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: flashvhigh1,flashvhigh2,flashhigh1,flashhigh2,flashstd1,flashstd2,flashlow1,flashlow2 modes will be tried for version default 18-Nov-2014 19:51:41.272 [get_iplayer.out-1] INFO u.c.m.sagetv.catchup.CatchupServer - INFO: Trying flashvhigh1 mode to record tv: - 18-Nov-2014 19:51:41.902 [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 19:51:41.904 [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 19:51:41.906 [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 19:51:41.907 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Recording of ClickAustraliaSpecial tidying up 18-Nov-2014 19:51:41.908 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Going to stop recording of ClickAustraliaSpecial 18-Nov-2014 19:51:41.910 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Stopping recording of ClickAustraliaSpecial 18-Nov-2014 19:51:41.918 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killing processes matching: .*iplayer.*b04sm3sj.* 18-Nov-2014 19:51:42.004 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killing process 644 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 19:51:42.065 [kill.out-3] INFO u.c.m.sagetv.catchup.CatchupServer - SUCCESS: The process with PID 644 has been terminated. 18-Nov-2014 19:51:42.069 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killed process 644 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 19:51:42.071 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killing process 6424 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 19:51:42.121 [kill.err-4] INFO u.c.m.sagetv.catchup.CatchupServer - ERROR: The process "6424" not found. 18-Nov-2014 19:51:42.123 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killed process 6424 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 19:51:42.125 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Killed processes [644, 6424] which matched .*iplayer.*b04sm3sj.* 18-Nov-2014 19:51:42.126 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Stopped recording of ClickAustraliaSpecial 18-Nov-2014 19:51:42.128 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Deleting files for recording ClickAustraliaSpecial 18-Nov-2014 19:51:42.129 [catchup-recorder-0] INFO u.c.m.sagetv.catchup.CatchupServer - Removing recording ClickAustraliaSpecial