Here is the error for “The Little Mermaid Live”.
2019/11/05 22:01:08 [DVR] Error running job 1573001940-ch1004 The Wonderful World of Disney Presents The Little Mermaid Live!: CreateFile D:\TV: The system cannot find the path specified.
I’m also seeing multiple errors that are saying the device is not ready - but the recording is fine when watching it.
It’s odd because they all recorded just fine, the entire recording is perfect.
I’m also seeing this from time to time, sometimes TV-E, other time OTA. Here is a log for the most recent interrupted recording; I actually don’t know what it looks like, although my wife has not complained as of yet. I can copy/paste additional log files as necessary... please let me know:
2019/11/06 19:59:50 [DVR] Starting job 1573088390-57 Riverdale on ch=[46.1]
2019/11/06 19:59:50 [TNR] Opened connection to 107011FA for ch46.1 WXCW HD
2019/11/06 19:59:50 [DVR] Recording for job 1573088390-57 from 107011FA ch46.1 into "TV/Riverdale/Riverdale S04E05 Chapter SixtyTwo Witness for the Prosecution 2019-11-06-1959.mpg" for 1h5m9.941459543s
2019/11/06 19:59:50 [IDX] Generating video index for job 1573088390-57
2019/11/06 21:04:51 [TNR] Closed connection to 107011FA for ch46.1 WXCW HD
2019/11/06 21:04:51 [DVR] Job 1573088390-57 Riverdale ended prematurely: 8.343669207s
2019/11/06 21:04:51 [DVR] Starting job 1573088390-57 Riverdale on ch=[46.1]
2019/11/06 21:04:51 [DVR] Waiting 22h55m8.248887451s until next job 1573174800-25 Superstore
2019/11/06 21:04:52 [TNR] Opened connection to 107011FA for ch46.1 WXCW HD
2019/11/06 21:04:52 [DVR] Recording for job 1573088390-57 from 107011FA ch46.1 into "TV/Riverdale/Riverdale S04E05 Chapter SixtyTwo Witness for the Prosecution 2019-11-06-1959.mpg" for 8.248828567s
2019/11/06 21:05:00 [TNR] Closed connection to 107011FA for ch46.1 WXCW HD
2019/11/06 21:05:00 [DVR] Finished job 1573088390-57 Riverdale
2019/11/06 21:05:00 [DVR] Waiting 22h54m59.550920823s until next job 1573174800-25 Superstore
2019/11/06 21:05:00 [DVR] Processing file-3261: TV/Riverdale/Riverdale S04E05 Chapter SixtyTwo Witness for the Prosecution 2019-11-06-1959.mpg
2019/11/06 21:05:01 [DVR] Running commercial detection on file 3261 (TV/Riverdale/Riverdale S04E05 Chapter SixtyTwo Witness for the Prosecution 2019-11-06-1959.mpg)
2019/11/06 21:23:04 [DVR] Commercial detection finished with 14 markers.
Please paste the other errors.
It sounds like your D:\ drive is failing.
The recording ended 8s before it was supposed to. It restarted at that point so not much was lost.
Obviously looking at the time stamps you are right... any thoughts as to why it would do that? I add a 5min buffer to all my recordings as I found sometimes there was a lag in my TV-E start/stop times (at least when I first started using TV-E so I added the buffer to ensure the entire recording was complete either via OTA or TV-E.
The 8s thing is weird. Could be some sort of bug. Do you have other examples of interrupted recordings in the logs? Are they all just a few seconds off?
Here’s the log from today. Had a couple movies scheduled to record and they show failures but look fine when viewing.
D:\TV\Movies\Jumanji (1995) 2019-11-07-0139.mpg: The device is not ready.
2019/11/07 02:12:48 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 02:12:48 [DVR] Error running job 1573109940-22 Doomsday Preppers: write D:\TV\TV\Doomsday Preppers\Doomsday Preppers S01E07 2012-03-13 Into the Spider Hole 2019-11-07-0159.mpg: The device is not ready.
2019/11/07 02:12:49 [DVR] Starting job 1573108740-ch1439 Jumanji (1995) on ch=[1439 163]
2019/11/07 02:12:50 [TNR] Opened connection to 13177781 for ch1439 LOGO
2019/11/07 02:12:50 [DVR] Starting job 1573109940-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 02:12:50 [DVR] Waiting 46m9.6696705s until next job 1573113540-22 Doomsday Preppers
2019/11/07 02:12:50 [DVR] Waiting 46m9.6656739s until next job 1573113540-22 Doomsday Preppers
2019/11/07 02:12:50 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 02:12:52 [DVR] Recording for job 1573109940-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S01E07 2012-03-13 Into the Spider Hole 2019-11-07-0159.mpg" for 48m9.6696705s
2019/11/07 02:12:52 [DVR] Recording for job 1573108740-ch1439 from 13177781 ch1439 into "Movies\Jumanji (1995) 2019-11-07-0139.mpg" for 1h48m10.210342s
2019/11/07 02:13:23 [DVR] Commercial detection finished with 10 markers.
2019/11/07 02:13:23 [IDX] Generating video index for file-241: Doomsday Preppers S03E14 2014-01-14 Americans Not Americants 2019-11-07-0059.mpg
2019/11/07 02:13:58 [ERR] Generating video index for file-241 failed: Couldn't reindex file: D:\TV\TV\Doomsday Preppers\Doomsday Preppers S03E14 2014-01-14 Americans Not Americants 2019-11-07-0059.mpg: EOF
2019/11/07 02:59:00 [DVR] Starting job 1573113540-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 02:59:00 [DVR] Waiting 59m59.9980905s until next job 1573117140-22 Doomsday Preppers
2019/11/07 02:59:00 [TNR] Sharing existing connection to 13177781 for ch871 NGCHD (clients=2, len=0)
2019/11/07 02:59:00 [DVR] Recording for job 1573113540-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg" for 1h1m59.9970911s
2019/11/07 02:59:00 [IDX] Generating video index for job 1573113540-22
2019/11/07 03:01:00 [DVR] Finished job 1573109940-22 Doomsday Preppers
2019/11/07 03:01:00 [DVR] Waiting 57m59.519091s until next job 1573117140-22 Doomsday Preppers
2019/11/07 03:01:00 [DVR] Processing file-243: TV\Doomsday Preppers\Doomsday Preppers S01E07 2012-03-13 Into the Spider Hole 2019-11-07-0159.mpg
2019/11/07 03:01:01 [DVR] Running commercial detection on file 243 (TV\Doomsday Preppers\Doomsday Preppers S01E07 2012-03-13 Into the Spider Hole 2019-11-07-0159.mpg)
2019/11/07 03:13:41 [TNR] Closed connection to 13177781 for ch1439 LOGO
2019/11/07 03:13:41 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 03:13:41 [DVR] Error running job 1573108740-ch1439 Jumanji (1995): write D:\TV\Movies\Jumanji (1995) 2019-11-07-0139.mpg: The device is not ready.
2019/11/07 03:13:41 [DVR] Error running job 1573113540-22 Doomsday Preppers: write D:\TV\TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg: The device is not ready.
2019/11/07 03:13:42 [DVR] Starting job 1573108740-ch1439 Jumanji (1995) on ch=[1439 163]
2019/11/07 03:13:43 [TNR] Opened connection to 13177781 for ch1439 LOGO
2019/11/07 03:13:43 [DVR] Starting job 1573113540-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 03:13:43 [DVR] Waiting 45m16.5733016s until next job 1573117140-22 Doomsday Preppers
2019/11/07 03:13:43 [DVR] Waiting 45m16.5703033s until next job 1573117140-22 Doomsday Preppers
2019/11/07 03:13:43 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 03:13:45 [DVR] Recording for job 1573113540-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg" for 47m16.5733016s
2019/11/07 03:13:45 [DVR] Recording for job 1573108740-ch1439 from 13177781 ch1439 into "Movies\Jumanji (1995) 2019-11-07-0139.mpg" for 47m17.0942892s
2019/11/07 03:14:16 [DVR] Commercial detection finished with 10 markers.
2019/11/07 03:14:17 [IDX] Generating video index for file-243: Doomsday Preppers S01E07 2012-03-13 Into the Spider Hole 2019-11-07-0159.mpg
2019/11/07 03:14:27 [ERR] Generating video index for file-243 failed: Couldn't reindex file: D:\TV\TV\Doomsday Preppers\Doomsday Preppers S01E07 2012-03-13 Into the Spider Hole 2019-11-07-0159.mpg: EOF
2019/11/07 03:28:25 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 03:28:25 [DVR] Error running job 1573113540-22 Doomsday Preppers: write D:\TV\TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg: The device is not ready.
2019/11/07 03:28:25 [TNR] Closed connection to 13177781 for ch1439 LOGO
2019/11/07 03:28:25 [DVR] Error running job 1573108740-ch1439 Jumanji (1995): write D:\TV\Movies\Jumanji (1995) 2019-11-07-0139.mpg: The device is not ready.
2019/11/07 03:28:26 [DVR] Starting job 1573108740-ch1439 Jumanji (1995) on ch=[1439 163]
2019/11/07 03:28:26 [TNR] Opened connection to 13177781 for ch1439 LOGO
2019/11/07 03:28:26 [DVR] Starting job 1573113540-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 03:28:26 [DVR] Waiting 30m33.0652351s until next job 1573117140-22 Doomsday Preppers
2019/11/07 03:28:26 [DVR] Waiting 30m33.0602385s until next job 1573117140-22 Doomsday Preppers
2019/11/07 03:28:27 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 03:28:28 [DVR] Recording for job 1573113540-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg" for 32m33.0652351s
2019/11/07 03:28:28 [DVR] Recording for job 1573108740-ch1439 from 13177781 ch1439 into "Movies\Jumanji (1995) 2019-11-07-0139.mpg" for 32m33.5702214s
2019/11/07 03:59:00 [DVR] Starting job 1573117140-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 03:59:00 [DVR] Waiting 59m59.9435675s until next job 1573120740-22 Doomsday Preppers
2019/11/07 03:59:00 [TNR] Sharing existing connection to 13177781 for ch871 NGCHD (clients=2, len=0)
2019/11/07 03:59:00 [DVR] Recording for job 1573117140-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg" for 1h1m59.923579s
2019/11/07 03:59:00 [IDX] Generating video index for job 1573117140-22
2019/11/07 04:00:47 [SYS] Created database snapshot: backup-20191107.040047
2019/11/07 04:00:47 [SYS] Removing old backup backup-20191013.101125
2019/11/07 04:01:00 [TNR] Closed connection to 13177781 for ch1439 LOGO
2019/11/07 04:01:00 [DVR] Finished job 1573108740-ch1439 Jumanji (1995)
2019/11/07 04:01:00 [DVR] Waiting 57m59.4811738s until next job 1573120740-22 Doomsday Preppers
2019/11/07 04:01:00 [DVR] Finished job 1573113540-22 Doomsday Preppers
2019/11/07 04:01:00 [DVR] Processing file-242: Movies\Jumanji (1995) 2019-11-07-0139.mpg
2019/11/07 04:01:00 [DVR] Waiting 57m59.4461924s until next job 1573120740-22 Doomsday Preppers
2019/11/07 04:01:01 [DVR] Running commercial detection on file 242 (Movies\Jumanji (1995) 2019-11-07-0139.mpg)
2019/11/07 04:01:33 [SYS] Bonjour service stopped.
2019/11/07 04:01:39 [DVR] Recording engine stopped.
2019/11/07 04:02:02 [M3U8] Generator: err=cannot replace "D:\\TV\\Streaming\\m3u8\\245\\stream.m3u8" with tempfile "D:\\TV\\Streaming\\m3u8\\245\\stream.m3u8814547196": replace D:\TV\Streaming\m3u8\245\stream.m3u8814547196 D:\TV\Streaming\m3u8\245\stream.m3u8: A device which does not exist was specified.
2019/11/07 04:02:02 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 04:02:02 [DVR] Error running job 1573117140-22 Doomsday Preppers: write D:\TV\TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg: The device is not ready.
2019/11/07 04:02:06 [DVR] Recording engine started in D:\TV
2019/11/07 04:02:06 [DVR] Starting job 1573117140-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 04:02:06 [DVR] Waiting 56m53.5058917s until next job 1573120740-22 Doomsday Preppers
2019/11/07 04:02:06 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 04:02:07 [DVR] Recording for job 1573117140-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg" for 58m53.5058917s
2019/11/07 04:02:07 [DVR] Processing file-244: TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg
2019/11/07 04:02:08 [DVR] Running commercial detection on file 244 (TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg)
2019/11/07 04:02:10 [SYS] Bonjour service running for dvr-alpha-htpc.local. [192.168.1.115]
2019/11/07 04:02:11 [DVR] Commercial detection finished with 6 markers.
2019/11/07 04:02:11 [IDX] Generating video index for file-242: Jumanji (1995) 2019-11-07-0139.mpg
2019/11/07 04:02:14 [ERR] Generating video index for file-242 failed: Couldn't reindex file: D:\TV\Movies\Jumanji (1995) 2019-11-07-0139.mpg: EOF
2019/11/07 04:02:14 database not open
panic: runtime error: invalid memory address or nil pointer dereference
[signal 0xc0000005 code=0x0 addr=0x0 pc=0xca6239]
goroutine 104 [running]:
github.com/fancybits/channels-server/dvr.(*File).Refresh(...)
github.com/fancybits/channels-server@/dvr/file.go:107
github.com/fancybits/channels-server/dvr.(*Recorder).RunProcessor(0xc000832a80)
github.com/fancybits/channels-server@/dvr/recorder.go:361 +0x989
created by github.com/fancybits/channels-server/dvr.(*Recorder).Run
github.com/fancybits/channels-server@/dvr/recorder.go:158 +0xb0
2019/11/07 04:02:15 [SYS] Starting Channels DVR v2019.10.29.0031 (windows-x86_64 pid:14948) in C:\ProgramData\ChannelsDVR\data
2019/11/07 04:02:15 [SYS] Running as a Windows system service.
2019/11/07 04:02:16 [HDR] Found 1 devices
2019/11/07 04:02:17 [SYS] Started HTTP Server
2019/11/07 04:02:17 [DVR] Recording engine started in D:\TV
2019/11/07 04:02:17 [DVR] Starting job 1573117140-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 04:02:17 [DVR] Waiting 56m42.4585586s until next job 1573120740-22 Doomsday Preppers
2019/11/07 04:02:17 [SYS] Bonjour service running for dvr-alpha-htpc.local. [192.168.1.115]
2019/11/07 04:02:17 [DVR] Processing file-244: TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg
2019/11/07 04:02:17 [DVR] Running commercial detection on file 244 (TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg)
2019/11/07 04:02:18 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 04:02:18 [DVR] Recording for job 1573117140-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg" for 58m42.4585586s
2019/11/07 04:02:27 [IDX] Pruned 8475 expired airings from USA-PA54007-X in 311.8194ms.
2019/11/07 04:02:27 [IDX] Pruned 2134 expired airings from X-TVE in 79.9542ms.
2019/11/07 04:04:28 [NAT] Failed to discover upnp: write udp4 0.0.0.0:57064->239.255.255.250:1900: i/o timeout
2019/11/07 04:04:28 [NAT] Failed to discover router using natpmp and upnp.
2019/11/07 04:18:44 [DVR] Commercial detection finished with 10 markers.
2019/11/07 04:18:44 [IDX] Generating video index for file-244: Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg
2019/11/07 04:18:49 [ERR] Generating video index for file-244 failed: Couldn't reindex file: D:\TV\TV\Doomsday Preppers\Doomsday Preppers S02E17 2013-03-19 Gonna Be a Big Bang 2019-11-07-0259.mpg: EOF
2019/11/07 04:18:49 [DVR] Processing file-242: Movies\Jumanji (1995) 2019-11-07-0139.mpg
2019/11/07 04:18:49 [DVR] Running commercial detection on file 242 (Movies\Jumanji (1995) 2019-11-07-0139.mpg)
2019/11/07 04:24:07 [DVR] Commercial detection finished with 22 markers.
2019/11/07 04:24:07 [IDX] Generating video index for file-242: Jumanji (1995) 2019-11-07-0139.mpg
2019/11/07 04:24:07 [ERR] Generating video index for file-242 failed: Couldn't reindex file: D:\TV\Movies\Jumanji (1995) 2019-11-07-0139.mpg: EOF
2019/11/07 04:24:08 [SYS] Created database snapshot: backup-20191107.042407
2019/11/07 04:24:08 [SYS] Removing old backup backup-20191013.124700
2019/11/07 04:58:59 [DVR] Waiting 963µs until next job 1573120740-22 Doomsday Preppers
2019/11/07 04:59:00 [DVR] Starting job 1573120740-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 04:59:00 [DVR] Waiting 1h29m59.9969662s until next job 1573126140-ch6088 Revenge of the Nerds (1984)
2019/11/07 04:59:00 [TNR] Sharing existing connection to 13177781 for ch871 NGCHD (clients=2, len=0)
2019/11/07 04:59:00 [DVR] Recording for job 1573120740-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg" for 1h1m59.9969662s
2019/11/07 04:59:00 [IDX] Generating video index for job 1573120740-22
2019/11/07 05:01:00 [DVR] Finished job 1573117140-22 Doomsday Preppers
2019/11/07 05:01:00 [DVR] Waiting 1h27m59.4094249s until next job 1573126140-ch6088 Revenge of the Nerds (1984)
2019/11/07 05:01:00 [DVR] Processing file-245: TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg
2019/11/07 05:01:01 [DVR] Running commercial detection on file 245 (TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg)
2019/11/07 05:14:23 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 05:14:23 [DVR] Error running job 1573120740-22 Doomsday Preppers: write D:\TV\TV\Doomsday Preppers\Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg: The device is not ready.
2019/11/07 05:14:23 [DVR] Commercial detection failed with exit status 103
2019/11/07 05:14:23 [IDX] Generating video index for file-245: Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg
2019/11/07 05:14:23 [ERR] Generating video index for file-245 failed: Couldn't open file: D:\TV\TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg: open D:\TV\TV\Doomsday Preppers\Doomsday Preppers S02E12 2013-02-05 Hit the Ground Running 2019-11-07-0359.mpg: The system cannot find the path specified.
2019/11/07 05:14:24 [DVR] Starting job 1573120740-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 05:14:24 [DVR] Waiting 1h14m35.9015795s until next job 1573126140-ch6088 Revenge of the Nerds (1984)
2019/11/07 05:14:24 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 05:14:24 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 05:14:24 [DVR] Error running job 1573120740-22 Doomsday Preppers: CreateFile D:\TV: The system cannot find the path specified.
2019/11/07 05:14:54 [DVR] Starting job 1573120740-22 Doomsday Preppers on ch=[6070 1473 871 109]
2019/11/07 05:14:54 [DVR] Waiting 1h14m5.3896188s until next job 1573126140-ch6088 Revenge of the Nerds (1984)
2019/11/07 05:14:55 [TNR] Opened connection to 13177781 for ch871 NGCHD
2019/11/07 05:14:55 [DVR] Recording for job 1573120740-22 from 13177781 ch871 into "TV\Doomsday Preppers\Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg" for 46m5.3896188s
2019/11/07 06:01:00 [TNR] Closed connection to 13177781 for ch871 NGCHD
2019/11/07 06:01:00 [DVR] Finished job 1573120740-22 Doomsday Preppers
2019/11/07 06:01:00 [DVR] Waiting 27m59.5052158s until next job 1573126140-ch6088 Revenge of the Nerds (1984)
2019/11/07 06:01:00 [DVR] Processing file-246: TV\Doomsday Preppers\Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg
2019/11/07 06:01:01 [DVR] Running commercial detection on file 246 (TV\Doomsday Preppers\Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg)
2019/11/07 06:09:49 [DVR] Commercial detection finished with 8 markers.
2019/11/07 06:09:49 [IDX] Generating video index for file-246: Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg
2019/11/07 06:10:00 [ERR] Generating video index for file-246 failed: Couldn't reindex file: D:\TV\TV\Doomsday Preppers\Doomsday Preppers S03E05 2013-10-29 Take Our Country Back 2019-11-07-0459.mpg: EOF
2019/11/07 06:29:00 [DVR] Starting job 1573126140-ch6088 Revenge of the Nerds (1984) on ch=[6088 1438 893 164]
2019/11/07 06:29:00 [DVR] Waiting 1h59m59.9901367s until next job 1573133340-ch6088 Revenge of the Nerds II: Nerds in Paradise (1987)
2019/11/07 06:29:00 [TNR] Opened connection to 13177781 for ch893 IFCHD
2019/11/07 06:29:01 [DVR] Recording for job 1573126140-ch6088 from 13177781 ch893 into "Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg" for 2h1m59.9901367s
2019/11/07 06:29:01 [IDX] Generating video index for job 1573126140-ch6088
2019/11/07 07:00:00 [TNR] Closed connection to 13177781 for ch893 IFCHD
2019/11/07 07:00:00 [DVR] Error running job 1573126140-ch6088 Revenge of the Nerds (1984): write D:\TV\Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg: The device is not ready.
2019/11/07 07:00:01 [DVR] Starting job 1573126140-ch6088 Revenge of the Nerds (1984) on ch=[6088 1438 893 164]
2019/11/07 07:00:01 [DVR] Waiting 1h28m58.3767833s until next job 1573133340-ch6088 Revenge of the Nerds II: Nerds in Paradise (1987)
2019/11/07 07:00:02 [TNR] Opened connection to 13177781 for ch893 IFCHD
2019/11/07 07:00:03 [DVR] Recording for job 1573126140-ch6088 from 13177781 ch893 into "Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg" for 1h30m58.3767833s
2019/11/07 07:44:56 [TNR] Closed connection to 13177781 for ch893 IFCHD
2019/11/07 07:44:56 [DVR] Error running job 1573126140-ch6088 Revenge of the Nerds (1984): write D:\TV\Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg: The device is not ready.
2019/11/07 07:44:57 [DVR] Starting job 1573126140-ch6088 Revenge of the Nerds (1984) on ch=[6088 1438 893 164]
2019/11/07 07:44:57 [DVR] Waiting 44m2.3710502s until next job 1573133340-ch6088 Revenge of the Nerds II: Nerds in Paradise (1987)
2019/11/07 07:44:58 [TNR] Opened connection to 13177781 for ch893 IFCHD
2019/11/07 07:44:59 [DVR] Recording for job 1573126140-ch6088 from 13177781 ch893 into "Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg" for 46m2.3710502s
2019/11/07 08:09:27 [TNR] Closed connection to 13177781 for ch893 IFCHD
2019/11/07 08:09:27 [DVR] Error running job 1573126140-ch6088 Revenge of the Nerds (1984): write D:\TV\Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg: The device is not ready.
2019/11/07 08:09:28 [DVR] Starting job 1573126140-ch6088 Revenge of the Nerds (1984) on ch=[6088 1438 893 164]
2019/11/07 08:09:28 [DVR] Waiting 19m31.3269512s until next job 1573133340-ch6088 Revenge of the Nerds II: Nerds in Paradise (1987)
2019/11/07 08:09:29 [TNR] Opened connection to 13177781 for ch893 IFCHD
2019/11/07 08:09:30 [DVR] Recording for job 1573126140-ch6088 from 13177781 ch893 into "Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg" for 21m31.3269512s
2019/11/07 08:18:43 [TNR] Closed connection to 13177781 for ch893 IFCHD
2019/11/07 08:18:43 [DVR] Error running job 1573126140-ch6088 Revenge of the Nerds (1984): write D:\TV\Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg: The device is not ready.
2019/11/07 08:18:45 [DVR] Starting job 1573126140-ch6088 Revenge of the Nerds (1984) on ch=[6088 1438 893 164]
2019/11/07 08:18:45 [DVR] Waiting 10m14.9977175s until next job 1573133340-ch6088 Revenge of the Nerds II: Nerds in Paradise (1987)
2019/11/07 08:18:45 [TNR] Opened connection to 13177781 for ch893 IFCHD
2019/11/07 08:18:46 [DVR] Recording for job 1573126140-ch6088 from 13177781 ch893 into "Movies\Revenge of the Nerds (1984) 2019-11-07-0629.mpg" for 12m14.9977175s
"ERROR_NOT_READY normally indicates a serious hardware failure."
Is this a mapped drive, or a physical USB drive?
Looks like its just intermittently throwing errors. Sometimes the first attempt fails, then the DVR tries to record again 30s later and it works.
In other cases, it keeps failing. Like for "Revenge of the Nerds (1984)" it doesn't look like it ever started recording cuz of the errors.
It’s an external drive connected with an esata to usb cable adapter to my pc.
I never have any issues accessing the drive and I ran a few programs to check it, no errors on any of them.
I’ll hook up another drive this weekend and try recording to it, see if I get the same errors.
Here is one that just happened... it’s also interrupted by 8 sec. obviously on this one I only have a 1 minute buffer added onto the end of the show.
2019/11/07 19:26:14 [TNR] Opened connection to TVE-Comcast_SSO for ch6051 CNBC
2019/11/07 19:59:50 [DVR] Waiting 9.014549478s until next job 1573174800-25 Superstore
2019/11/07 20:00:00 [DVR] Starting job 1573174800-25 Superstore on ch=[6000 20.1]
2019/11/07 20:00:00 [DVR] Waiting 59m49.980390793s until next job 1573178390-56 The Good Place
2019/11/07 20:00:00 [TNR] Opened connection to 107011FA for ch20.1 WBBH
2019/11/07 20:00:00 [DVR] Recording for job 1573174800-25 from 107011FA ch20.1 into "TV/Superstore/Superstore S05E07 Shoplifter Rehab 2019-11-07-2000.mpg" for 30m59.980256375s
2019/11/07 20:00:00 [IDX] Generating video index for job 1573174800-25
2019/11/07 20:30:51 [TNR] Closed connection to 107011FA for ch20.1 WBBH
2019/11/07 20:30:51 [DVR] Job 1573174800-25 Superstore ended prematurely: 8.315955792s
2019/11/07 20:30:51 [DVR] Starting job 1573174800-25 Superstore on ch=[6000 20.1]
2019/11/07 20:30:51 [DVR] Waiting 28m58.222659871s until next job 1573178390-56 The Good Place
2019/11/07 20:30:52 [TNR] Opened connection to 107011FA for ch20.1 WBBH
2019/11/07 20:30:52 [DVR] Recording for job 1573174800-25 from 107011FA ch20.1 into "TV/Superstore/Superstore S05E07 Shoplifter Rehab 2019-11-07-2000.mpg" for 8.222603268s
2019/11/07 20:31:00 [TNR] Closed connection to 107011FA for ch20.1 WBBH
2019/11/07 20:31:00 [DVR] Finished job 1573174800-25 Superstore
2019/11/07 20:31:00 [DVR] Waiting 28m49.432790322s until next job 1573178390-56 The Good Place
2019/11/07 20:31:00 [DVR] Processing file-3262: TV/Superstore/Superstore S05E07 Shoplifter Rehab 2019-11-07-2000.mpg
2019/11/07 20:31:01 [DVR] Running commercial detection on file 3262 (TV/Superstore/Superstore S05E07 Shoplifter Rehab 2019-11-07-2000.mpg)
2019/11/07 20:35:49 [DVR] Commercial detection finished with 10 markers.
2019/11/07 20:35:50 [IDX] Generating video index for file-3262: Superstore S05E07 Shoplifter Rehab 2019-11-07-2000.mpg
Is time sync (NTP) enabled on your NAS? Could be a time keeping issue.
It is... should it not be?
It should be enabled, yes.
Can you paste the OS/CPU section of the settings page.
OS
QNAP HS-251+
Linux
(kernel: 4.14.24-qnap)
CPU
4 cores / Intel(R) Celeron(R) CPU J1900 @ 1.99GHz
load averages: 1.98 2.18 2.18
RAM
1.84 GB
51.9% free
I also am getting this recording interrupted issue but the recordings are fine. It’s random when it happens as far as I can tell. I’ve had 3 or 4 recording over the past week or two that this has happened to but again I watch them and nothing seems wrong with the recordings
Please post the logs from the time of the recording
So I go to recordings, select the one that says interrupted, select the gear icon and select and choose view recording log and the only thing in the log is:
loc=188 pkt=2 pid=48 what="pat_and_pmt" offset=0 length=376 contiguous=true changed=true packets=2
Even looking at ones that aren't marked as interrupted the log is just 1 line like the above
Check the main dvr log on http://127.0.0.1:8089/log?n=20000 and scroll back to the time of the recording
Here you go for the last one it happened to
2019/11/07 21:00:00 [DVR] Starting job 1573178400-1 The Good Place on ch=[41.1 2.1]
2019/11/07 21:00:00 [TNR] Opened connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:00:00 [DVR] Recording for job 1573178400-1 from 103B2BDF ch41.1 into "TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg" for 29m59.9870621s
2019/11/07 21:00:00 [IDX] Generating video index for job 1573178400-1
2019/11/07 21:29:57 [TNR] Closed connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:29:57 [DVR] Job 1573178400-1 The Good Place ended prematurely: 2.4620733s
2019/11/07 21:29:57 [DVR] Starting job 1573178400-1 The Good Place on ch=[41.1 2.1]
2019/11/07 21:29:57 [TNR] Opened connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:29:57 [DVR] Recording for job 1573178400-1 from 103B2BDF ch41.1 into "TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg" for 2.4492577s
2019/11/07 21:30:00 [TNR] Closed connection to 103B2BDF for ch41.1 CIII
2019/11/07 21:30:00 [DVR] Finished job 1573178400-1 The Good Place
2019/11/07 21:30:00 [DVR] Processing file-43: TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg
2019/11/07 21:30:01 [DVR] Running commercial detection on file 43 (TV\The Good Place\The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg)
2019/11/07 21:39:05 [DVR] Commercial detection finished with 8 markers.
2019/11/07 21:39:06 [IDX] Generating video index for file-43: The Good Place S04E07 Help Is Other People 2019-11-07-2100.mpg
2019/11/07 21:40:00 [IDX] Finished video index generation for file-43 in 53s