Page 11 of 52 FirstFirst ... 91011121321 ... LastLast
Results 151 to 165 of 776

Thread: MovieLoader: HME meets MFS_FTP

  1. #151
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    I understand that this was a quick and dirty test. I'm just wondering if there is a similar quick and dirty test for the similar issue with the PORT command.

    I would appreciate a patch to add the appropriate unset commands for every close.
    -- gfb107

  2. #152
    Join Date
    Aug 2004
    Posts
    4,075
    Quote Originally Posted by gfb107 View Post
    I understand that this was a quick and dirty test. I'm just wondering if there is a similar quick and dirty test for the similar issue with the PORT command.
    Yeah, that's probably the right place for the PORT command.

  3. #153
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    Well, after making the suggested changed to mfs_ftp.tcl, everything seemed to be working better.

    But I've run into another problem. This is a bit different. I setup 3 shows to import, and 1 show to export, and then just waited to see what would happen. Shortly after starting the import of the second show, my hr10-250 rebooted. I managed to get in and save the level 5 debug log before mfs_ftp started back up.

    The log is attached.
    Last edited by JJBliss; 02-20-2007 at 03:25 PM.
    -- gfb107

  4. #154
    Join Date
    Aug 2004
    Posts
    4,075
    Quote Originally Posted by gfb107 View Post
    But I've run into another problem. This is a bit different. I setup 3 shows to import, and 1 show to export, and then just waited to see what would happen. Shortly after starting the import of the second show, my hr10-250 rebooted. I managed to get in and save the level 5 debug log before mfs_ftp started back up.

    The log is attached.
    Looks like it is trying to write the meta data for the second show into MFS and it is getting a lock conflict. It seems to have retried twice before the reboot. Anything in the other logs? tvlog, tverr, kernel, messages may all have useful information about the crash.

    This is going to sound like a kludge (it is), but you might consider putting a small delay in between transfers to allow MFS to quiesce.
    Last edited by Jamie; 02-07-2007 at 12:24 PM.

  5. #155
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    tverr
    Code:
    Feb 7 03:50:27 (none) TvMomHpkSwitchSource.C[262]: GetData: Overrun
    Feb 7 14:12:35 (none) TmkAssertionFailure[13172]: : (Audit, line 32 ())
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: Tmk Fatal Error: Activity HandleDataChanged <13172> strayed! (block timestamp 5262601934791)
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: bt -t /tvbin/tivoapp
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: tcd 1
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: hpk Series2
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: build b-firefly-takehome @248982 2006.10.20-1631 release-mips []
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: pack 6.3b-01-2
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x00400000 /tvbin/tivoapp
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02000000 /lib/libc.so.6
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02200000 /lib/libm.so.6
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02400000 /lib/libpthread.so.0
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02600000 /lib/libutil.so.1
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02800000 /lib/libtvutil.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02a00000 /lib/libtmk.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x02c00000 /lib/libtvstructures.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x2aaa8000 /lib/ld.so.1
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x2ab04000 /lib/libhpkoss.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x2ab50000 /platform/lib/libhpkhl.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x2ac18000 /platform/lib/libhpkll.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x2ac5c000 /lib/libdl.so.2
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: read 0x2aca0000 /lib/libcdaudio.so
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: 0x02a7ea98 0x00613d28 0x0056e508 0x00563144 0x005ed78c 0x005ec21c 0x00566068 0x0059c264 0x0056c324 0x005b08b8 0x005eaf70 0x006a235c 0x00749c54 0x006ccf50 0x00640d94 0x0071437c 0x004726b4 0x00ef1318 0x02a2871c 0x02a304bc 0x02a298cc 0x02a28a48 0x02a28834 0x02a60cb8 0x02a2871c 0x02a28630 0x02a2b03c 0x02a3c7e4 0x02a3c6cc 0x02a3c57c 0x02a3bad8
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: Tmk Fatal Error: Activity HandleDataChanged <13172>: assertion failure
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: Tmk Fatal Error: Thread died due to signal -2
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: Invoking rule 834: rebooting system
    tvlog
    Code:
    Feb 7 14:12:00 (none) TmkActivityStats[206]: ContextManagerActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:00 (none) TmkActivityStats[206]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:00 (none) TmkActivityStats[206]: Timer : Cur#: 3, Max#: 4, #Trig: 215514, Time:2 min 50 sec
    Feb 7 14:12:00 (none) TmkActivityStats[206]: Sema : Cur#: 11, Max#: 19, #Trig: 39298, Time:1 min 11 sec
    Feb 7 14:12:00 (none) TmkActivityStats[206]: Callme: Cur#: 2, Max#: 5, #Trig: 71389, Time: 9 sec
    Feb 7 14:12:01 (none) Scheduler[13172]: Candidates Sched 3 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:01 (none) Scheduler[13172]: nonCandidates Sched 188 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:01 (none) Scheduler[13172]: Processing PS [<?>]1:439352
    Feb 7 14:12:03 (none) TmkActivityStats[13184]: TvDbGenreMapperApgActivity-->(Pri:0 Time Sample: 6 h 0 min)
    Feb 7 14:12:03 (none) TmkActivityStats[13184]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:03 (none) TmkActivityStats[13184]: Timer : Cur#: 1, Max#: 1, #Trig: 4318, Time: 28 sec
    Feb 7 14:12:03 (none) TmkActivityStats[13184]: Sema : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:03 (none) TmkActivityStats[13184]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:03 (none) Scheduler[13172]: GetAllSimItems nCand 3 psId 0
    Feb 7 14:12:04 (none) Scheduler[13172]: SimulationWatch: Hold 178 pri 1
    Feb 7 14:12:05 (none) InputState[208]: GetOnNow()->FNeedsRefresh Input:0
    Feb 7 14:12:06 (none) TmkActivityStats[13184]: MessageManagerActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:06 (none) TmkActivityStats[13184]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:06 (none) TmkActivityStats[13184]: Timer : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:06 (none) TmkActivityStats[13184]: Sema : Cur#: 8, Max#: 8, #Trig: 74, Time: 138 msec
    Feb 7 14:12:06 (none) TmkActivityStats[13184]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:06 (none) TmkActivityStats[13180]: MessageProcessor-->(Pri:0 Time Sample: 6 h 0 min)
    Feb 7 14:12:06 (none) TmkActivityStats[13180]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:06 (none) TmkActivityStats[13180]: Timer : Cur#: 1, Max#: 1, #Trig: 72, Time: 3 sec
    Feb 7 14:12:06 (none) TmkActivityStats[13180]: Sema : Cur#: 1, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:06 (none) TmkActivityStats[13180]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:08 (none) InputState[208]: GetOnNow()->FNeedsRefresh Input:1
    Feb 7 14:12:09 (none) TvVideoGuts[208]: InputState processing took 163 msecs
    Feb 7 14:12:09 (none) TvVideoGuts[208]: Total Heartbeat processing took 166 msecs
    Feb 7 14:12:09 (none) Scheduler[13172]: Candidates Sched 3 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:09 (none) Scheduler[13172]: nonCandidates Sched 188 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:09 (none) Scheduler[13172]: Processing PS [<?>]1:439386
    Feb 7 14:12:10 (none) TmkActivityStats[147]: TvBusConnectionActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:10 (none) TmkActivityStats[147]: Fd : Cur#: 1, Max#: 1, #Trig: 11425, Time: 18 sec
    Feb 7 14:12:10 (none) TmkActivityStats[147]: Timer : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[147]: Sema : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[147]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[13138]: TvBusConnectionActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:10 (none) TmkActivityStats[13138]: Fd : Cur#: 1, Max#: 1, #Trig: 112, Time: 239 msec
    Feb 7 14:12:10 (none) TmkActivityStats[13138]: Timer : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[13138]: Sema : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[13138]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[149]: TvChoreRunServerActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:10 (none) TmkActivityStats[149]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[149]: Timer : Cur#: 1, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:10 (none) TmkActivityStats[149]: Sema : Cur#: 6, Max#: 9, #Trig: 15385, Time: 10 sec
    Feb 7 14:12:10 (none) TmkActivityStats[149]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:13 (none) TmkActivityStats[13180]: TvRecorderCanaryActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:13 (none) TmkActivityStats[13180]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:13 (none) TmkActivityStats[13180]: Timer : Cur#: 1, Max#: 1, #Trig: 3601, Time: 317 msec
    Feb 7 14:12:13 (none) TmkActivityStats[13180]: Sema : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:13 (none) TmkActivityStats[13180]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:14 (none) Scheduler[13172]: GetAllSimItems nCand 2 psId 0
    Feb 7 14:12:14 (none) TmkActivityStats[13185]: ContextManagerCanaryActivity-->(Pri:1 Time Sample: 6 h 0 min)
    Feb 7 14:12:14 (none) TmkActivityStats[13185]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:14 (none) TmkActivityStats[13185]: Timer : Cur#: 1, Max#: 1, #Trig: 3601, Time: 309 msec
    Feb 7 14:12:14 (none) TmkActivityStats[13185]: Sema : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:14 (none) TmkActivityStats[13185]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:14 (none) Scheduler[13172]: SimulationWatch: Hold 179 pri 1
    Feb 7 14:12:16 (none) TmkActivityStats[282]: TvRendezvous-->(Pri:0 Time Sample: 6 h 0 min)
    Feb 7 14:12:16 (none) TmkActivityStats[282]: Fd : Cur#: 2, Max#: 2, #Trig: 2245, Time: 7 sec
    Feb 7 14:12:16 (none) TmkActivityStats[282]: Timer : Cur#: 1, Max#: 1, #Trig: 2221, Time: 1 sec
    Feb 7 14:12:16 (none) TmkActivityStats[282]: Sema : Cur#: 12, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:16 (none) TmkActivityStats[282]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:16 (none) TmkActivityStats[12248]: KnownHostBeaconActivity-->(Pri:0 Time Sample: 6 h 0 min)
    Feb 7 14:12:16 (none) TmkActivityStats[12248]: Fd : Cur#: 1, Max#: 2, #Trig: 2506, Time: 19 sec
    Feb 7 14:12:16 (none) TmkActivityStats[12248]: Timer : Cur#: 1, Max#: 2, #Trig: 4321, Time: 5 sec
    Feb 7 14:12:16 (none) TmkActivityStats[12248]: Sema : Cur#: 4, Max#: 4, #Trig: 1424, Time: 10 sec
    Feb 7 14:12:16 (none) TmkActivityStats[12248]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:18 (none) Scheduler[13172]: Candidates Sched 2 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:18 (none) Scheduler[13172]: nonCandidates Sched 189 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:18 (none) Scheduler[13172]: Processing PS [<?>]1:439451
    Feb 7 14:12:18 (none) Scheduler[13172]: GetAllSimItems nCand 2 psId 0
    Feb 7 14:12:19 (none) Scheduler[13172]: SimulationWatch: Hold 180 pri 1
    Feb 7 14:12:20 (none) InputState[208]: GetOnNow()->FNeedsRefresh Input:0
    Feb 7 14:12:20 (none) TvVideoGuts[208]: InputState processing took 117 msecs
    Feb 7 14:12:20 (none) TvVideoGuts[208]: Total Heartbeat processing took 121 msecs
    Feb 7 14:12:24 (none) InputState[208]: GetOnNow()->FNeedsRefresh Input:1
    Feb 7 14:12:24 (none) TvVideoGuts[208]: InputState processing took 106 msecs
    Feb 7 14:12:24 (none) TvVideoGuts[208]: Total Heartbeat processing took 108 msecs
    Feb 7 14:12:24 (none) Scheduler[13172]: Candidates Sched 2 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:24 (none) Scheduler[13172]: nonCandidates Sched 189 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:25 (none) Scheduler[13172]: Processing PS [<?>]1:440490
    Feb 7 14:12:25 (none) TmkActivityStats[13149]: SpigotSpigotMapActivity-->(Pri:0 Time Sample: 6 h 0 min)
    Feb 7 14:12:25 (none) TmkActivityStats[13149]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:25 (none) TmkActivityStats[13149]: Timer : Cur#: 1, Max#: 1, #Trig: 721, Time: 57 sec
    Feb 7 14:12:25 (none) TmkActivityStats[13149]: Sema : Cur#: 1, Max#: 1, #Trig: 12, Time: 2 sec
    Feb 7 14:12:25 (none) TmkActivityStats[13149]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:25 (none) TmkActivityStats[12969]: SpigotGroupsActivity-->(Pri:0 Time Sample: 6 h 0 min)
    Feb 7 14:12:25 (none) TmkActivityStats[12969]: Fd : Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:25 (none) TmkActivityStats[12969]: Timer : Cur#: 1, Max#: 1, #Trig: 721, Time: 862 msec
    Feb 7 14:12:25 (none) TmkActivityStats[12969]: Sema : Cur#: 1, Max#: 1, #Trig: 6, Time: 1 sec
    Feb 7 14:12:25 (none) TmkActivityStats[12969]: Callme: Cur#: 0, Max#: 0, #Trig: 0, Time: 0 usec
    Feb 7 14:12:26 (none) Recorder[245]: Checking schedule
    Feb 7 14:12:27 (none) Scheduler[13172]: GetAllSimItems nCand 2 psId 0
    Feb 7 14:12:27 (none) DbNowShowingBucketList.C[13184]: (645) Dump this=0x5ee73220 items=0 Nodes=18 pDirM=/Recording/NowShowingByBucketTitle
    Feb 7 14:12:27 (none) DbNowShowingBucketList.C[13184]: (645) Dump this=0x5ee73370 items=97 Nodes=0 pDirM=/Recording/NowShowingByFlatTitle
    Feb 7 14:12:27 (none) DbNowShowingBucketList.C[13184]: (645) Dump this=0x5ee732b0 items=97 Nodes=0 pDirM=/Recording/NowShowingByClassic
    Feb 7 14:12:27 (none) Scheduler[13172]: SimulationWatch: Hold 181 pri 1
    Feb 7 14:12:29 (none) Scheduler[13172]: Candidates Sched 2 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:29 (none) Scheduler[13172]: nonCandidates Sched 189 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:29 (none) Scheduler[13172]: Processing PS [<?>]1:440504
    Feb 7 14:12:30 (none) Scheduler[13172]: GetAllSimItems nCand 2 psId 0
    Feb 7 14:12:30 (none) Scheduler[13172]: SimulationWatch: Hold 182 pri 1
    Feb 7 14:12:32 (none) DbNowShowingBucketList.C[13184]: (645) Dump this=0x5ee73220 items=0 Nodes=19 pDirM=/Recording/NowShowingByBucketTitle
    Feb 7 14:12:32 (none) DbNowShowingBucketList.C[13184]: (645) Dump this=0x5ee73370 items=98 Nodes=0 pDirM=/Recording/NowShowingByFlatTitle
    Feb 7 14:12:32 (none) DbNowShowingBucketList.C[13184]: (645) Dump this=0x5ee732b0 items=98 Nodes=0 pDirM=/Recording/NowShowingByClassic
    Feb 7 14:12:32 (none) Scheduler[13172]: Candidates Sched 2 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:32 (none) Scheduler[13172]: nonCandidates Sched 189 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:32 (none) Scheduler[13172]: Processing PS [<?>]1:440529
    Feb 7 14:12:32 (none) Scheduler[13172]: GetAllSimItems nCand 2 psId 0
    Feb 7 14:12:33 (none) Scheduler[13172]: SimulationWatch: Hold 183 pri 1
    Feb 7 14:12:34 (none) Scheduler[13172]: Candidates Sched 2 Cancel Tuner 0 Disk 0 MaxR 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:34 (none) Scheduler[13172]: nonCandidates Sched 190 Cancel Tuner 0 Disk 0 Max 0 NoReRec 0 SameShow 0 Negative 0 CopyProtection 0
    Feb 7 14:12:35 (none) InputState[208]: GetOnNow()->FNeedsRefresh Input:0
    Feb 7 14:12:39 (none) InputState[208]: GetOnNow()->FNeedsRefresh Input:1
    Jan 2 00:00:18 (none) TmkInit[53]: Starting program osdwriter
    This is going to sound like a kludge (it is), but you might consider putting a small delay in between transfers to allow MFS to quiesce.
    OK, how long? 1 sec? 500msec?
    -- gfb107

  6. #156
    Join Date
    Aug 2004
    Posts
    4,075
    Quote Originally Posted by gfb107 View Post
    tverr
    Code:
    Feb 7 03:50:27 (none) TvMomHpkSwitchSource.C[262]: GetData: Overrun
    Feb 7 14:12:35 (none) TmkAssertionFailure[13172]: : (Audit, line 32 ())
    Feb 7 14:12:35 (none) Activity HandleDataChanged[13172]: Tmk Fatal Error: Activity HandleDataChanged <13172> strayed! (block timestamp 5262601934791)
    ...
    I've seen those HandleDataChanged crashes before, but I'm not quite sure what is causing them. If I'm reading the logs right, I think the crash is coming from the Scheduler thread, which is probably looking at the MFS data structures while mfs_ftp is trying to update them.


    OK, how long? 1 sec? 500msec?
    Like any good kludge, you'll have to figure this out by trial and error! I'd start high (maybe 10 seconds?) and work your way down to the smallest value that prevents the crashes. Of course, there is no guarantee this will help at all.

  7. #157
    Join Date
    Aug 2004
    Posts
    4,075
    Quote Originally Posted by gfb107 View Post
    I would appreciate a patch to add the appropriate unset commands for every close.
    Here's a lightly tested mfs_ftp patch to work around the PASV problem. It is to be applied after the jerrymc mfs_ftp.20070121 rollup patch. If, after testing, there is general agreement that this is an improvement with no negatives, we can roll a new mfs_ftp rollup patch.

    I don't expect this to make any differences for the HandleDataChanged reboot problems.

    [edit: the patch can now be found here]
    Last edited by Jamie; 02-21-2007 at 04:45 PM. Reason: patch moved

  8. #158
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    That patch seems to be working well.

    But I'm now running into problems that I think are caused by transfers and caching overlapping and interfering with each other.

    I think I'm going to put the cache updates on the queue along with the show transfers. That's not going to get done in the next few days, however.
    -- gfb107

  9. #159
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    Alright, here's a version that does the cache updates as jobs on the transfer queue, to make sure there is no interference between cache updates and transfers. I also added a one second delay between jobs.

    I also switched from my home-brew FTP client interface to Jakarta Commons Net.

    With this version and Jamie's [url=http://www.dealdatabase.com/forum/showpost.php?p=275434&postcount=15702072007]patchhttp://www.dealdatabase.com/forum/showpost.php?p=276094&postcount=180 above I was able to queue up 2 imports and 1 export (all to/from an HDVR2), all of which completed successfully.

    *********** Updated ************
    This version has been removed as it is now obsolete. Use the version here.
    Last edited by gfb107; 02-17-2007 at 04:28 PM.
    -- gfb107

  10. #160
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    I forgot to mention that although the cache updates are done through the transfer queue, and they are included in the pending count, there is no progress indicator for them. Also, completed cache updates are not kept in the transfer history.
    -- gfb107

  11. #161
    Join Date
    Jan 2006
    Posts
    120
    This is what I get when running the new version just released two threads up. #159

    I deleted the cache folder and reran MovieLoader and got the same errors.
    Not sure if it something on my computer or another TiVo has a wrong date. I will edit it so it does not select the computer as a server to see if that helps.
    I DID notice on the version before this one that I would receive java errors also sometimes when trying to transfer shows. I wonder if its because of Galleon.


    C:\MovieLoader>java -jar C:\MovieLoader\MovieLoader.jar MovieLoader
    HME SDK 1.4 (TiVo, Inc.)
    LOG: MovieLoaderFactory.initFactory()
    LOG: Parsing "C:\MovieLoader\MovieLoader.properties"
    LOG: "Pams" represents "ftp://192.168.0.211:3105/tmf/"
    LOG: "Dads" represents "ftp://192.168.0.209:3105/tmf/"
    LOG: "Dad's Living Room" represents "ftp://192.168.0.240:3105/tmf/"
    LOG: "Pam's Bedroom" represents "ftp://192.168.0.212:3105/tmf/"
    LOG: "Jacuzzi" represents "ftp://192.168.0.202:3105/tmf/"
    LOG: "UpStairs" represents "ftp://192.168.0.201:3105/tmf/"
    LOG: "Bedroom" represents "ftp://192.168.0.251:3105/tmf/"
    LOG: "Basement" represents "ftp://192.168.0.203:3105/tmf/"
    LOG: "Living Room" represents "ftp://192.168.0.208:3105/tmf/"
    LOG: "Computer Holdings" represents "t:\TiVo Shows"
    LOG: "CBC News at 6:35 AM & PM" represents "T:\CBC Tivo Shows"
    LOG: added factory
    MDNS: http://192.168.0.1:1332/MovieLoader/
    java.text.ParseException: Unparseable date: "PM Mon Oct 09, 2006"
    at java.text.DateFormat.parse(Unknown Source)
    at MediaFile.<init>(MediaFile.java:108)
    at FtpMediaFile.<init>(FtpMediaFile.java:60)
    at FtpMediaFolder.updateCache(FtpMediaFolder.java:121)
    at CacheUpdater.perform(CacheUpdater.java:93)
    at FtpThread.run(FtpThread.java:98)
    java.text.ParseException: Unparseable date: "PM Mon Oct 09, 2006"
    at java.text.DateFormat.parse(Unknown Source)
    at MediaFile.<init>(MediaFile.java:108)
    at FtpMediaFile.<init>(FtpMediaFile.java:60)
    at FtpMediaFolder.updateCache(FtpMediaFolder.java:121)
    at CacheUpdater.perform(CacheUpdater.java:93)
    at FtpThread.run(FtpThread.java:98)
    Last edited by Butch; 02-10-2007 at 04:59 PM. Reason: Add info

  12. #162
    Join Date
    May 2005
    Posts
    317
    I've had the "unparseable date" errors as well. I think they were caused by screwy names of orphaned mfs_ftp transfers that never made it from the previous version of MovieLoader. Rebooting the offending Tivo to remove those orphans cleared the problem up for me. This newest version of MovieLoader, along with the mfs_ftp patches, has been working flawlessly for me so far. Here's a log of some of those unparseable date errors:

    Code:
    HME SDK 1.4 (TiVo, Inc.)
    LOG: MovieLoaderFactory.initFactory()
    LOG:   Showing files in "D:\Video"
    2/9/07 11:05:39 AM: Done!
    LOG: added factory
    MDNS: http://192.168.0.100:7288/MovieLoader/
    LOG: 192.168.0.153 icon.png HTTP GET - to factory /MovieLoader/
    LOG: HME receiver connected
    java.text.ParseException: Unparseable date: "PM Mon Mar 27, 2006"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    java.text.ParseException: Unparseable date: "10.02 A"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    java.text.ParseException: Unparseable date: "PM Wed Mar 08, 2006"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    java.text.ParseException: Unparseable date: "PM Tue Mar 07, 2006"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    MovieLoader[#1,uri=null] warning: resource 2063 not found.
    MovieLoader[#1,uri=null] warning: resource 2063 not found.
    LOG: Received event for unknown resource id. Id = 2063, event = 2063.RESOURCE_IN
    FO(complete, {})
    LOG: connection to receiver closed
    LOG: HME receiver disconnected
    LOG: 192.168.0.153 icon.png HTTP GET - to factory /MovieLoader/
    LOG: HME receiver connected
    java.text.ParseException: Unparseable date: "PM Mon Mar 27, 2006"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    java.text.ParseException: Unparseable date: "10.02 A"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    java.text.ParseException: Unparseable date: "PM Wed Mar 08, 2006"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    java.text.ParseException: Unparseable date: "PM Tue Mar 07, 2006"
            at java.text.DateFormat.parse(Unknown Source)
            at MediaFile.<init>(MediaFile.java:104)
            at LocalMediaFile.<init>(LocalMediaFile.java:45)
            at LocalMediaFile.<init>(LocalMediaFile.java:40)
            at LocalMediaFolder.list(LocalMediaFolder.java:72)
            at MainMenuScreen.run(MainMenuScreen.java:138)
            at java.lang.Thread.run(Unknown Source)
    LOG: connection to receiver closed
    LOG: HME receiver disconnected
    LOG: 192.168.0.153 icon.png HTTP GET - to factory /MovieLoader/

  13. #163
    Join Date
    Aug 2002
    Posts
    75
    The latest version seems to be working fine.

    But I do have one problem that I think has been around for awhile now:
    Nearly all of my media files are .tmf files stored on my video server, and the date listed for all of them in MovieLoader's main list is 12/31/??. When I view the details screen the correct date is shown.

  14. #164
    Join Date
    Aug 2001
    Location
    Raleigh, NC
    Posts
    349
    When you say "stored on my video server", does that mean an FTP server, or on your PC where MovieLoader is running? Are the file names consistent with how mfs_ftp names files? The information in the list is extracted from the file names, while the information on the details screen comes from parsing the XML data (from a .xml file with the same name as the .tmf file, or from the embedded XML in the .tmf if it a local file).

    The details
    -- gfb107

  15. #165
    Join Date
    Aug 2002
    Posts
    75
    Well, that answers that question. I renamed the files to simply be the name of the movie which explains why I'm getting a bogus date.

    Now I have a question about the future of MovieLoader. I saw a post you made in a tivoserver thread about possibly working together with tivoserver to provide transcoding of other formats. But the response from the tivoserver group seemed to indicate that wouldn't be possible. Do you have any other plans to support any kind of transcoding engine for MovieLoader? It would be awesome if we could pipe the output from the ty-enabled ffmpeg into MovieLoader. Please don't take this question the wrong way, as it stands MovieLoader is incredible! Even without transcoding it has provided me with an interface I have been wanting for several years!

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •