Refresh Schedule Data no longer working

[tscottsexton 1]

I have a HDHR Connect and a paid Schedules Direct account and after setting everything up, everything was working fine. A few days ago I noticed that I no longer have any schedule data.  I'm not sure when the problem started as my logs don't go back far enough.  

 

When I click on Refresh Guide in Scheduled Tasks, here's what I get in the logs:

 

 

 

2016-09-14 11:55:24.3335 Info HttpClient: HttpClientManager POST: https://json.schedulesdirect.org/20141201/token
2016-09-14 11:55:24.4482 Info App: Authenticated with Schedules Direct token: b5eb017438774777c4c9749776220dee
2016-09-14 11:55:24.4482 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/lineups/USA-OTA-27502
2016-09-14 11:55:24.5329 Info App: Found 71 channels on the lineup on ScheduleDirect
2016-09-14 11:55:24.5329 Info App: Mapping Stations to Channel
2016-09-14 11:55:24.5332 Error App: Error adding metadata
    *** Error Report ***
    Version: 3.0.7100.0
    Command line: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe -programdata /var/lib/emby-server -restartpath /usr/lib/emby-server/restart.sh
    Operating system: Unix 3.16.0.4
    Processor count: 4
    64-Bit OS: True
    64-Bit Process: True
    Program data path: /var/lib/emby-server
    Mono: 4.4.2 (Stable 4.4.2.11/f72fe45 Tue Aug 30 15:47:09 UTC 2016)
    Application Path: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe
    Value cannot be null.
    Parameter name: source
    System.ArgumentNullException
     at System.Linq.Enumerable.FirstOrDefault[TSource] (IEnumerable`1 source, System.Func`2 predicate) <0x404f4330 + 0x0013f> in <filename unknown>:0
     at MediaBrowser.Server.Implementations.LiveTv.Listings.SchedulesDirect+<AddMetadata>c__async1.MoveNext () <0x41bc3920 + 0x00b5b> in <filename unknown>:0
    --- End of stack trace from previous location where exception was thrown ---
     at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () <0x7fd9e2c5e2a0 + 0x00029> in <filename unknown>:0
     at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) <0x7fd9e2c5c170 + 0x000a7> in <filename unknown>:0
     at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) <0x7fd9e2c5c0f0 + 0x0006b> in <filename unknown>:0
     at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) <0x7fd9e2c5c0a0 + 0x0003a> in <filename unknown>:0
     at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () <0x7fd9e2c5c6e0 + 0x00012> in <filename unknown>:0
     at MediaBrowser.Server.Implementations.LiveTv.EmbyTV.EmbyTV+<GetChannelsAsync>c__async2.MoveNext () <0x41bb94f0 + 0x00e65> in <filename unknown>:0
    
2016-09-14 11:55:24.5405 Info App: Refreshing guide with 14 days of guide data
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 4.1 with name UNC-TV
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 4.2 with name ROOTLE
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 4.3 with name UNC-EX
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 4.4 with name NCCHL
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 5.1 with name WRAL_HD
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 11.1 with name WTVD-D1
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 11.2 with name WTVD-D2
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 17.1 with name WNCN-HD
2016-09-14 11:55:24.5405 Info App: No Schedules Direct Station found for channel 17.3 with name Justice
2016-09-14 11:55:24.5410 Info App: No Schedules Direct Station found for channel 22.1 with name WLFL-HD
2016-09-14 11:55:24.5410 Info App: No Schedules Direct Station found for channel 22.2 with name ASN24/7
2016-09-14 11:55:24.5410 Info App: No Schedules Direct Station found for channel 28.1 with name WRDC-HD
2016-09-14 11:55:24.5410 Info App: No Schedules Direct Station found for channel 28.3 with name CometTV
2016-09-14 11:55:24.5410 Info App: No Schedules Direct Station found for channel 50.1 with name WRAZ-HD
2016-09-14 11:55:24.7409 Info HttpClient: HttpClientManager POST: https://www.mb3admin.com/admin/service/registration/validate
2016-09-14 11:55:24.8186 Info TaskManager: Refresh Guide Completed after 0 minute(s) and 0 seconds
2016-09-14 11:55:24.8195 Info ServerManager: Sending web socket message ScheduledTaskEnded

 

Any ideas?

 

Thanks!

 

Scott

[MrUltraHappyMan 0]

I'm experiencing the exact same problem.  It also appears that I'm using the same zip code as you, so maybe it's something location specific.

[tscottsexton 1]

I've reported basically the same thing to Schedules Direct, so we'll see what happens in that direction. I'm hoping they don't come back with, "It's an Emby problem."

[Luke 37112]

It's an Emby problem most likely, just not sure what it is yet. I will test that lineup. Thanks.

[Luke 37112]

I found the issue. It's resolved for the next release of Emby Server. The fix will be available on the beta channel later tonight. Thanks.

[tscottsexton 1]

You guys rock. Thanks!

[tscottsexton 1]

Just got the new server release and no joy.  Here is my log now:

 

 

 

2016-09-18 08:25:49.1892 Info TaskManager: Executing Refresh Guide
2016-09-18 08:25:49.1920 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/lineups/USA-OTA-27502
2016-09-18 08:25:49.1920 Info HttpServer: HTTP Response 204 to 123.456.789.012. Time: 14ms. http://myserver.mydomain.net:8096/emby/ScheduledTasks/Running/3c6a16ed7db828baeb3bb3c1cff74810
2016-09-18 08:25:49.1991 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true. UserAgent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/52.0.2743.116 Safari/537.36
2016-09-18 08:25:49.2029 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 4ms. http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true
2016-09-18 08:25:49.2576 Info App: Found 71 channels on the lineup on ScheduleDirect
2016-09-18 08:25:49.2576 Info App: Mapping Stations to Channel
2016-09-18 08:25:49.2576 Info App: Added 0 channels to the dictionary
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 4.1 UNC-TV
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 4.2 ROOTLE
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 4.3 UNC-EX
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 4.4 NCCHL
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 5.1 WRAL_HD
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 11.1 WTVD-D1
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 11.2 WTVD-D2
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 17.1 WNCN-HD
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 17.3 Justice
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 22.1 WLFL-HD
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 22.2 ASN24/7
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 28.1 WRDC-HD
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 28.3 CometTV
2016-09-18 08:25:49.2576 Info App: Schedules Direct doesnt have data for channel: 50.1 WRAZ-HD
2016-09-18 08:25:49.2651 Info App: Refreshing guide with 14 days of guide data
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 4.1 with name UNC-TV
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 4.2 with name ROOTLE
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 4.3 with name UNC-EX
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 4.4 with name NCCHL
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 5.1 with name WRAL_HD
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 11.1 with name WTVD-D1
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 11.2 with name WTVD-D2
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 17.1 with name WNCN-HD
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 17.3 with name Justice
2016-09-18 08:25:49.2651 Info App: No Schedules Direct Station found for channel 22.1 with name WLFL-HD
2016-09-18 08:25:49.2660 Info App: No Schedules Direct Station found for channel 22.2 with name ASN24/7
2016-09-18 08:25:49.2660 Info App: No Schedules Direct Station found for channel 28.1 with name WRDC-HD
2016-09-18 08:25:49.2660 Info App: No Schedules Direct Station found for channel 28.3 with name CometTV
2016-09-18 08:25:49.2660 Info App: No Schedules Direct Station found for channel 50.1 with name WRAZ-HD
2016-09-18 08:25:49.2900 Info HttpClient: HttpClientManager POST: https://www.mb3admin.com/admin/service/registration/validate
2016-09-18 08:25:49.3695 Info TaskManager: Refresh Guide Completed after 0 minute(s) and 0 seconds

 

Is this a schedules direct problem now?

[Luke 37112]

Just got the new server release and no joy.  Here is my log now:

 

 

Is this a schedules direct problem now?

 

Maybe. The original error was due to data missing from the response that we were expecting. Now that we've handled the error to prevent the Emby error, it brings to light the true problem, which is the data. 

 

Try to use the channel mapping feature on the live tv setup page. If there are no channels available in the Schedules Direct data to map to, then you might need to report to them.

[tscottsexton 1]

OK, just in case anyone else is having this problem:

 

I reset everything I could think of that involved the guide.  I removed the HDHR and Schedules direct from Emby server, connected to my HDHR and removed all favorite/delete settings (made all of the stars next to channels grey), and rescanned for channels.  I then went back into Emby server, added the HDHR, put in my info for schedules direct, and the guide finally downloaded just fine.

 

Thanks for the help!

[tscottsexton 1]

And now it's not working...  I just tried the exact steps above again and it doesn't work again.  So I'm not sure it's a Schedules Direct problem.  Here are the logs beginning with me adding the HDHR to Emby server:

 

 

 

2016-09-19 10:17:52.1206 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/LiveTv/Info. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:17:52.1235 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:17:52.1378 Info HttpServer: HTTP Response 200 to 192.168.XXX.XXX. Time: 14ms. http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true
2016-09-19 10:17:52.1452 Info HttpServer: HTTP Response 200 to 192.168.XXX.XXX. Time: 25ms. http://myserver.mydomain.net:8096/emby/LiveTv/Info
2016-09-19 10:17:52.1846 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration/livetv. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:17:52.1878 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 3ms. http://myserver.mydomain.net:8096/emby/System/Configuration/livetv
2016-09-19 10:17:55.6619 Info App: ScheduledTasksWebSocketListener stop transmitting over websocket to 123.456.789.012:52108
2016-09-19 10:18:05.7437 Info HttpServer: HTTP POST http://myserver.mydomain.net:8096/emby/LiveTv/TunerHosts. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:05.7610 Info HttpClient: HttpClientManager GET: http://192.168.35.6/discover.json
2016-09-19 10:18:05.7755 Info TaskManager: Queueing task RefreshChannelsScheduledTask
2016-09-19 10:18:05.7755 Info TaskManager: Executing Refresh Guide
2016-09-19 10:18:05.7787 Info HttpClient: HttpClientManager GET: http://192.168.35.6/lineup.json
2016-09-19 10:18:05.7787 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 36ms. http://myserver.mydomain.net:8096/emby/LiveTv/TunerHosts
2016-09-19 10:18:05.8113 Info App: Refreshing guide with 14 days of guide data
2016-09-19 10:18:05.8976 Info HttpClient: HttpClientManager POST: https://www.mb3admin.com/admin/service/registration/validate
2016-09-19 10:18:05.9828 Info TaskManager: Refresh Guide Completed after 0 minute(s) and 0 seconds
2016-09-19 10:18:05.9850 Info ServerManager: Sending web socket message ScheduledTaskEnded
2016-09-19 10:18:05.9850 Info TaskManager: ExecuteQueuedTasks
2016-09-19 10:18:06.0517 Info App: ScheduledTasksWebSocketListener Begin transmitting over websocket to 123.456.789.012:52108
2016-09-19 10:18:06.0521 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/LiveTv/Info. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:06.0521 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:06.0550 Info HttpClient: Checking for cache file /var/lib/emby-server/cache/httpclient/3b3e9206e728ea3a3b79e93ce8331aaf
2016-09-19 10:18:06.0550 Info HttpClient: HttpClientManager GET: http://123.123.123.123/tuners.html
2016-09-19 10:18:06.0567 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 4ms. http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true
2016-09-19 10:18:06.0786 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 27ms. http://myserver.mydomain.net:8096/emby/LiveTv/Info
2016-09-19 10:18:06.1251 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration/livetv. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:06.1283 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 3ms. http://myserver.mydomain.net:8096/emby/System/Configuration/livetv
2016-09-19 10:18:08.9983 Info App: ScheduledTasksWebSocketListener stop transmitting over websocket to 123.456.789.012:52108
2016-09-19 10:18:09.0493 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration/livetv. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:09.0526 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 3ms. http://myserver.mydomain.net:8096/emby/System/Configuration/livetv
2016-09-19 10:18:09.0932 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders/SchedulesDirect/Countries. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:09.0962 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/available/countries
2016-09-19 10:18:09.2992 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 206ms. http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders/SchedulesDirect/Countries
2016-09-19 10:18:16.3099 Info HttpServer: HTTP POST http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders?ValidateLogin=true. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:16.3155 Info TaskManager: Queueing task RefreshChannelsScheduledTask
2016-09-19 10:18:16.3155 Info TaskManager: Executing Refresh Guide
2016-09-19 10:18:16.3188 Error App: Error adding metadata
    *** Error Report ***
    Version: 3.0.7200.0
    Command line: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe -programdata /var/lib/emby-server -restartpath /usr/lib/emby-server/restart.sh
    Operating system: Unix 3.16.0.4
    Processor count: 4
    64-Bit OS: True
    64-Bit Process: True
    Program data path: /var/lib/emby-server
    Mono: 4.4.2 (Stable 4.4.2.11/f72fe45 Tue Aug 30 15:47:09 UTC 2016)
    Application Path: /usr/lib/emby-server/bin/MediaBrowser.Server.Mono.exe
    ListingsId required
    System.Exception
     at MediaBrowser.Server.Implementations.LiveTv.Listings.SchedulesDirect+<AddMetadata>c__async1.MoveNext () <0x4057ef60 + 0x00143> in <filename unknown>:0
    --- End of stack trace from previous location where exception was thrown ---
     at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () <0x7f068613a2a0 + 0x00029> in <filename unknown>:0
     at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess (System.Threading.Tasks.Task task) <0x7f0686138170 + 0x000a7> in <filename unknown>:0
     at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification (System.Threading.Tasks.Task task) <0x7f06861380f0 + 0x0006b> in <filename unknown>:0
     at System.Runtime.CompilerServices.TaskAwaiter.ValidateEnd (System.Threading.Tasks.Task task) <0x7f06861380a0 + 0x0003a> in <filename unknown>:0
     at System.Runtime.CompilerServices.ConfiguredTaskAwaitable+ConfiguredTaskAwaiter.GetResult () <0x7f06861386e0 + 0x00012> in <filename unknown>:0
     at MediaBrowser.Server.Implementations.LiveTv.EmbyTV.EmbyTV+<GetChannelsAsync>c__async2.MoveNext () <0x4053a2e0 + 0x00e65> in <filename unknown>:0
    
2016-09-19 10:18:16.3349 Info App: Refreshing guide with 14 days of guide data
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3349 Warn App: ListingsId is null, returning empty program list
2016-09-19 10:18:16.3847 Info HttpClient: HttpClientManager POST: https://www.mb3admin.com/admin/service/registration/validate
2016-09-19 10:18:16.3847 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 76ms. http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders?ValidateLogin=true
2016-09-19 10:18:16.4214 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration/livetv. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:16.4247 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 3ms. http://myserver.mydomain.net:8096/emby/System/Configuration/livetv
2016-09-19 10:18:16.4590 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders/SchedulesDirect/Countries. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:16.4622 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/available/countries
2016-09-19 10:18:16.4726 Info TaskManager: Refresh Guide Completed after 0 minute(s) and 0 seconds
2016-09-19 10:18:16.4744 Info ServerManager: Sending web socket message ScheduledTaskEnded
2016-09-19 10:18:16.4744 Info TaskManager: ExecuteQueuedTasks
2016-09-19 10:18:16.5044 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 46ms. http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders/SchedulesDirect/Countries
2016-09-19 10:18:26.6682 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders/Lineups?Id=d84fa1ec697b4e9aa3ddf2d14913472d&Location=27502&Country=USA. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:26.6849 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/headends?country=USA&postalcode=27502
2016-09-19 10:18:26.9494 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 281ms. http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders/Lineups?Id=d84fa1ec697b4e9aa3ddf2d14913472d&Location=27502&Country=USA
2016-09-19 10:18:30.6622 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration/livetv. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:30.6655 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 3ms. http://myserver.mydomain.net:8096/emby/System/Configuration/livetv
2016-09-19 10:18:30.7086 Info HttpServer: HTTP POST http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders?ValidateListings=true. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:30.7116 Info App: Headends on account
2016-09-19 10:18:30.7117 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/lineups
2016-09-19 10:18:30.7641 Info TaskManager: Queueing task RefreshChannelsScheduledTask
2016-09-19 10:18:30.7641 Info TaskManager: Executing Refresh Guide
2016-09-19 10:18:30.7673 Info HttpClient: HttpClientManager GET: https://json.schedulesdirect.org/20141201/lineups/USA-OTA-27502
2016-09-19 10:18:30.7682 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 60ms. http://myserver.mydomain.net:8096/emby/LiveTv/ListingProviders?ValidateListings=true
2016-09-19 10:18:30.8422 Info App: Found 71 channels on the lineup on ScheduleDirect
2016-09-19 10:18:30.8422 Info App: Mapping Stations to Channel
2016-09-19 10:18:30.8422 Info App: Added 0 channels to the dictionary
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 2.1 WFMY HD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 2.2 WFMY SD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 2.3 WFMYSD2
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 4.1 UNC-TV
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 4.2 ROOTLE
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 4.3 UNC-EX
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 4.4 NCCHL
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 5.1 WRAL_HD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 5.2 WRAL-SD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 11.1 WTVD-D1
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 11.2 WTVD-D2
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 11.3 WTVD-D3
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 17.1 WNCN-HD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 17.2 WNCN-SD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 17.3 Justice
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 20.1 WCWG
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 20.2 WCWG-D2
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 20.3 WCWG-D3
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 20.4 WCWG-D4
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 22.1 WLFL-HD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 22.2 ASN24/7
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.1 W24CP-D
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.2 3ABN-PR
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.3 3ABN-DD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.4 3ABN-ES
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.5 3ABN-RD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.6 3ABN-RL
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 24.7 Radio74
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 28.1 WRDC-HD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 28.2 Grit-TV
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 28.3 CometTV
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 40.1 WUVC-DT
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 40.2 UNIMAS
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 40.3 BOUNCE
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 40.4 GEtTV
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 50.1 WRAZ-HD
2016-09-19 10:18:30.8422 Info App: Schedules Direct doesnt have data for channel: 50.2 MeTV
2016-09-19 10:18:30.8588 Info App: Refreshing guide with 14 days of guide data
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 2.1 with name WFMY HD
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 2.2 with name WFMY SD
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 2.3 with name WFMYSD2
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 4.1 with name UNC-TV
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 4.2 with name ROOTLE
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 4.3 with name UNC-EX
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 4.4 with name NCCHL
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 5.1 with name WRAL_HD
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 5.2 with name WRAL-SD
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 11.1 with name WTVD-D1
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 11.2 with name WTVD-D2
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 11.3 with name WTVD-D3
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 17.1 with name WNCN-HD
2016-09-19 10:18:30.8588 Info App: No Schedules Direct Station found for channel 17.2 with name WNCN-SD
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 17.3 with name Justice
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 20.1 with name WCWG
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 20.2 with name WCWG-D2
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 20.3 with name WCWG-D3
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 20.4 with name WCWG-D4
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 22.1 with name WLFL-HD
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 22.2 with name ASN24/7
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.1 with name W24CP-D
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.2 with name 3ABN-PR
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.3 with name 3ABN-DD
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.4 with name 3ABN-ES
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.5 with name 3ABN-RD
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.6 with name 3ABN-RL
2016-09-19 10:18:30.8598 Info App: No Schedules Direct Station found for channel 24.7 with name Radio74
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 28.1 with name WRDC-HD
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 28.2 with name Grit-TV
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 28.3 with name CometTV
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 40.1 with name WUVC-DT
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 40.2 with name UNIMAS
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 40.3 with name BOUNCE
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 40.4 with name GEtTV
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 50.1 with name WRAZ-HD
2016-09-19 10:18:30.8607 Info App: No Schedules Direct Station found for channel 50.2 with name MeTV
2016-09-19 10:18:30.8857 Info HttpClient: HttpClientManager POST: https://www.mb3admin.com/admin/service/registration/validate
2016-09-19 10:18:30.9628 Info TaskManager: Refresh Guide Completed after 0 minute(s) and 0 seconds
2016-09-19 10:18:30.9652 Info ServerManager: Sending web socket message ScheduledTaskEnded
2016-09-19 10:18:30.9652 Info TaskManager: ExecuteQueuedTasks
2016-09-19 10:18:31.0246 Info App: ScheduledTasksWebSocketListener Begin transmitting over websocket to 123.456.789.012:52108
2016-09-19 10:18:31.0276 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/LiveTv/Info. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:31.0286 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:31.0306 Info HttpClient: Checking for cache file /var/lib/emby-server/cache/httpclient/3b3e9206e728ea3a3b79e93ce8331aaf
2016-09-19 10:18:31.0310 Info HttpClient: HttpClientManager GET: http://192.168.35.6/tuners.html
2016-09-19 10:18:31.0322 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 4ms. http://myserver.mydomain.net:8096/emby/ScheduledTasks?IsEnabled=true
2016-09-19 10:18:31.0538 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 26ms. http://myserver.mydomain.net:8096/emby/LiveTv/Info
2016-09-19 10:18:31.0933 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration/livetv. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:31.0965 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 3ms. http://myserver.mydomain.net:8096/emby/System/Configuration/livetv
2016-09-19 10:18:35.8774 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Logs. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:35.8774 Info HttpServer: HTTP GET http://myserver.mydomain.net:8096/emby/System/Configuration. UserAgent: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/53.0.2785.101 Safari/537.36
2016-09-19 10:18:35.8811 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 4ms. http://myserver.mydomain.net:8096/emby/System/Logs
2016-09-19 10:18:35.8817 Info HttpServer: HTTP Response 200 to 123.456.789.012. Time: 4ms. http://myserver.mydomain.net:8096/emby/System/Configuration
2016-09-19 10:18:36.0429 Info App: ScheduledTasksWebSocketListener stop transmitting over websocket to 123.456.789.012:52108

 

Does that help narrow down the problem?  I'll keep messing around on my end to see if I can figure out what's causing the problem.

 

As far as mapping the channels, when I do that they are already mapped properly so I'm not sure that does anything.

Edited September 19, 2016 by tscottsexton

[Luke 37112]

And now it's not working...  I just tried the exact steps above again and it doesn't work again.  So I'm not sure it's a Schedules Direct problem.  Here are the logs beginning with me adding the HDHR to Emby server:

 

 

Does that help narrow down the problem?  I'll keep messing around on my end to see if I can figure out what's causing the problem.

 

As far as mapping the channels, when I do that they are already mapped properly so I'm not sure that does anything.

 

You need to go back to the schedules direct setup page and select the listings you want. right now it looks like you haven't chosen anything.

[tscottsexton 1]

I don't know what to tell you.  It says antenna.  I've selected it at least a dozen times now.  I can't replicate the one time that it did work.

[Luke 37112]

Ok, nevermind, I mis-read. I see in subsequent log entries you filled out the listing you wanted. as a test, can you changing a mapping for a channel back and forth, then refresh the guide and see if that has any effect? thanks.

[tscottsexton 1]

No effect.  I get the exact same stuff in the logs.

[Luke 37112]

can you show a screenshot of your mappings? thanks.

[tscottsexton 1]

 

So I tried with two zip codes (27502 and 27513) that are right next to each other and got the same result. No guide data at all and this mapping which does not have the channel names.  Then I tried with a zip code closer in to the city and the guide downloads and displays fine. The mapping has the channel names. The zip code that works is 27608.  The screen shot is of 27502.  Since I've got a zip code that works, I'm good but you still might want to look into the problem as other zip codes may be affected.

Edited September 20, 2016 by tscottsexton

[Luke 37112]

Ok, thanks. I've fixed this. There was some empty data coming back in places we weren't expecting. It will be resolved for the next release, thanks.