Tvheadend

I'm attempting to use tvheadend git rev aecded7c4e on a raspberry pi with an Elgato EyeTV Hybrid. The hardware appears to be working but no Service names appear in the GUI and moreover attempts to "Play" a channel result in "Transport error indicator." I'd like to help debug but I'm not sure where to go next.

lsusb shows

Bus 001 Device 006: ID 0fd9:0008 Elgato Systems GmbH

dmesg identifies it as

DVB: registering adapter 0 frontend 0 (Auvitek AU8522 QAM/8VSB Frontend)...
Registered device AU0828 [Hauppauge HVR950Q]

w_scan successfully finds channels and services:

$w_scan -A1 -X -c US -fa -t3 > .azap/channels.conf
tune to: 8VSB f=183000 kHz
(time: 02:42) Info: no data from VCT
tune to: 8VSB f=195000 kHz
(time: 02:50) service is running. Channel number: 10:1. Name: 'OPB'
service is running. Channel number: 10:2. Name: 'OPBPlus'
service is running. Channel number: 10:3. Name: 'OPB-FM'
tune to: 8VSB f=207000 kHz
(time: 02:51) service is running. Channel number: 12:1. Name: 'KPTV-DT'
tune to: 8VSB f=521000 kHz
(time: 02:52) WARNING: compressed strings are not supported yet
service is running. Channel number: 22:1. Name: 'ION'
service is running. Channel number: 22:2. Name: 'KPXG qubo'
service is running. Channel number: 22:3. Name: 'KPXG ION Life'
tune to: 8VSB f=545000 kHz
(time: 02:53) Info: no data from PAT
Info: no data from VCT
tune to: 8VSB f=569000 kHz
(time: 02:59) service is running. Channel number: 49:1. Name: 'KPDX-DT'
tune to: 8VSB f=587000 kHz
(time: 03:00) service is running. Channel number: 32:1. Name: 'KRCW-DT'
service is running. Channel number: 32:2. Name: 'AntTV'
service is running. Channel number: 32:3. Name: 'ThisTV'
tune to: 8VSB f=605000 kHz
(time: 03:01) Info: no data from PAT
Info: no data from VCT
tune to: 8VSB f=629000 kHz
(time: 03:08) service is running. Channel number: 6:1. Name: 'KOIN-HD'
tune to: 8VSB f=641000 kHz
(time: 03:09) service is running. Channel number: 42:1. Name: 'KPXG LD ION'
service is running. Channel number: 42:2. Name: 'KPXG LD qubo'
service is running. Channel number: 42:3. Name: 'KPXG LD ION Life'
tune to: 8VSB f=647000 kHz
(time: 03:09) service is running. Channel number: 2:1. Name: 'KATU-HD'
service is running. Channel number: 2:2. Name: 'KATU-SD'
tune to: 8VSB f=659000 kHz
(time: 03:10) Info: no data from PAT
Info: no data from VCT
tune to: 8VSB f=665000 kHz
(time: 03:17) service is running. Channel number: 46:1. Name: 'KGWZ-LD'
tune to: 8VSB f=671000 kHz
(time: 03:18) service is running. Channel number: 47:1. Name: 'KUNP-LD'
service is running. Channel number: 47:2. Name: 'KUNP-LD'
tune to: 8VSB f=683000 kHz
(time: 03:18) Info: no data from PAT
Info: no data from VCT
dumping lists (23 services)
Done.

I can use azap to tune a channel:

$ azap -r OPB
using '/dev/dvb/adapter0/frontend0' and '/dev/dvb/adapter0/demux0'
tuning to 195000000 Hz
video pid 0x0031, audio pid 0x0034
status 00 | signal 0073 | snr 0087 | ber 000000ff | unc 000000ff |
status 1f | signal 00fa | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status 1f | signal 00fa | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
...

I can then successfully play the resulting mpeg transport stream with omxplayer (a video player specific to the raspberry pi that uses its onboard hardware acceleration):

$ omxplayer -r -o hdmi /dev/dvb/adapter0/dvr0
file : /dev/dvb/adapter0/dvr0 result 0 format mpegts audio streams 1 video streams 1 chapters 0 subtitles 0
Aspect : num 1 den 1 aspect 1.777778 display aspect 1.333333
Video codec omx-mpeg2 width 1920 height 1080 profile 4 fps 29.970030
Audio codec ac3 channels 8 samplerate 48000 bitspersample 16
Subtitle count : 0 state off : index 0
[Plays video on console]

I copied channel information from w_scan into /usr/local/share/tvheadend/data/dvb-scan/atsc:

A 183000000 8VSB
A 195000000 8VSB
A 207000000 8VSB
A 521000000 8VSB
A 569000000 8VSB
A 587000000 8VSB
A 629000000 8VSB
A 641000000 8VSB
A 647000000 8VSB
A 659000000 8VSB
A 665000000 8VSB
A 671000000 8VSB
A 683000000 8VSB

I run tvheadend:

$ sudo tvheadend -C -d
Nov 06 21:23:13 [DEBUG]:config: no configuration, loading defaults
Nov 06 21:23:13 [INFO]:charset: 71 entries loaded
Nov 06 21:23:20 [INFO]:dvb: Found adapter /dev/dvb/adapter0 (Auvitek AU8522 QAM/8VSB Frontend) via USB (480 Mbit/s)
Nov 06 21:23:20 [INFO]:dvb: Adapter /dev/dvb/adapter0 will run in full mux mode
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend647000000_0004" on "647,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend647000000_0003" on "647,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend671000000_0004" on "671,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend671000000_0003" on "671,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend195000000_0004" on "195,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend195000000_0003" on "195,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend195000000_0005" on "195,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend641000000_0003" on "641,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend641000000_0005" on "641,000 kHz"
Nov 06 21:23:20 [DEBUG]:dvb: Add service "_dev_dvb_adapter0_Auvitek_AU8522_QAM_8VSB_Frontend641000000_0004" on "641,000 kHz"
Nov 06 21:23:20 [INFO]:v4l: /dev/video0: au0828 Hauppauge HVR950Q au0828 1-1.3.2:1.0 capabilities: 0x05030011
Nov 06 21:23:20 [INFO]:v4l: /dev/video0: Standard #0: 0000000000001000 NTSC-M, frameperiod: 1001/30000, 525 lines
Nov 06 21:23:20 [INFO]:v4l: /dev/video0: Input #0: Television (Tuner), audio:0x0, tuner:0, standard:0000000000001000,
Nov 06 21:23:20 [INFO]:v4l: /dev/video0: Input #1: Composite (Camera), audio:0x0, tuner:0, standard:0000000000001000,
Nov 06 21:23:20 [INFO]:v4l: /dev/video0: Input #2: S-Video (Camera), audio:0x0, tuner:0, standard:0000000000001000,
Nov 06 21:23:20 [INFO]:v4l: /dev/video0: Format #0: Packed YUV2 [UYVY]
Nov 06 21:23:20 [WARNING]:v4l: /dev/video0: Device lacks MPEG encoder, device skipped
Nov 06 21:23:20 [INFO]:epggrab: module eit created
Nov 06 21:23:20 [INFO]:epggrab: module uk_freesat created
Nov 06 21:23:20 [INFO]:epggrab: module uk_freeview created
Nov 06 21:23:20 [INFO]:epggrab: module viasat_baltic created
Nov 06 21:23:20 [DEBUG]:opentv: dictionary skyit loaded
Nov 06 21:23:20 [DEBUG]:opentv: dictionary skyeng loaded
Nov 06 21:23:20 [DEBUG]:opentv: dictonaries loaded
Nov 06 21:23:20 [DEBUG]:opentv: genre map skyuk loaded
Nov 06 21:23:20 [DEBUG]:opentv: genre maps loaded
Nov 06 21:23:20 [INFO]:epggrab: module opentv-skyit created
Nov 06 21:23:20 [DEBUG]:opentv: provider skyit loaded
Nov 06 21:23:20 [INFO]:epggrab: module opentv-ausat created
Nov 06 21:23:20 [DEBUG]:opentv: provider ausat loaded
Nov 06 21:23:20 [INFO]:epggrab: module opentv-skyuk created
Nov 06 21:23:20 [DEBUG]:opentv: provider skyuk loaded
Nov 06 21:23:20 [DEBUG]:opentv: providers loaded
Nov 06 21:23:20 [INFO]:epggrab: module pyepg created
Nov 06 21:23:20 [INFO]:epggrab: module xmltv created
Nov 06 21:23:20 [DEBUG]:epggrab: using internal grab search
Nov 06 21:23:21 [INFO]:epgdb: loaded v2
Nov 06 21:23:21 [INFO]:epgdb: channels 0
Nov 06 21:23:21 [INFO]:epgdb: brands 0
Nov 06 21:23:21 [INFO]:epgdb: seasons 0
Nov 06 21:23:21 [INFO]:epgdb: episodes 0
Nov 06 21:23:21 [INFO]:epgdb: broadcasts 0
Nov 06 21:23:21 [INFO]:dvr: Creating new configuration ''
Nov 06 21:23:21 [WARNING]:dvr: Output directory for video recording is not yet configured for DVR configuration "". Defaulting to to "/root". This can be changed from the web user interface.
Nov 06 21:23:21 [INFO]:CSA: Using 32bit parallel descrambling
Nov 06 21:23:21 [NOTICE]:START: HTS Tvheadend version 3.3.111~gaecded7 started, running as PID:2582 UID:0 GID:0, settings located in '/root/.hts/tvheadend'
Nov 06 21:23:21 [DEBUG]:AVAHI: Adding service 'Tvheadend'
Nov 06 21:23:21 [INFO]:AVAHI: Service 'Tvheadend' successfully established.

I navigate to the web gui in Chrome (also tried Firefox) and go to Configuration > TV Adapters > Auvitek AU8522 QAM/8VSB Frontend > General. I choose Add DVB Network by location... and select my set of muxes from above. Tvheadend starts scanning:

Nov 06 21:23:22 [DEBUG]:dvb: /dev/dvb/adapter0 started dvr thread
Nov 06 21:23:22 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "647,000 kHz" (Initial autoscan)
Nov 06 21:23:22 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:23:22 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:23:22 [DEBUG]:eit: install table handlers
Nov 06 21:23:42 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "671,000 kHz" (Initial autoscan)
Nov 06 21:23:42 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:23:42 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:23:42 [DEBUG]:eit: install table handlers
Nov 06 21:24:02 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "195,000 kHz" (Initial autoscan)
Nov 06 21:24:02 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:24:02 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:24:02 [DEBUG]:eit: install table handlers
Nov 06 21:24:22 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "641,000 kHz" (Initial autoscan)
Nov 06 21:24:22 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:24:22 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:24:22 [DEBUG]:eit: install table handlers
Nov 06 21:24:42 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "659,000 kHz" (Initial autoscan)
Nov 06 21:24:42 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:24:42 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:24:42 [DEBUG]:eit: install table handlers
Nov 06 21:25:02 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "183,000 kHz" (Initial autoscan)
Nov 06 21:25:02 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:25:02 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:25:02 [DEBUG]:eit: install table handlers
Nov 06 21:25:22 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "683,000 kHz" (Initial autoscan)
Nov 06 21:25:22 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:25:22 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:25:22 [DEBUG]:eit: install table handlers
Nov 06 21:25:42 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "207,000 kHz" (Initial autoscan)
Nov 06 21:25:42 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:25:42 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:25:42 [DEBUG]:eit: install table handlers
Nov 06 21:26:02 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "587,000 kHz" (Initial autoscan)
Nov 06 21:26:02 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:26:02 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:26:02 [DEBUG]:eit: install table handlers
Nov 06 21:26:22 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "665,000 kHz" (Initial autoscan)
Nov 06 21:26:22 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:26:22 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:26:22 [DEBUG]:eit: install table handlers
Nov 06 21:26:23 [DEBUG]:dvb: "665,000 kHz" on adapter "Auvitek AU8522 QAM/8VSB Frontend", status changed to OK
Nov 06 21:26:42 [DEBUG]:dvb: "/dev/dvb/adapter0" tuning to "521,000 kHz" (Initial autoscan)
Nov 06 21:26:42 [DEBUG]:viasat_baltic: install table handlers
Nov 06 21:26:42 [DEBUG]:uk_freesat: install table handlers
Nov 06 21:26:42 [DEBUG]:eit: install table handlers

This goes for a while until "Map DVB services to channels..." is no longer greyed out. I click it.

The following screenshots show the results: no Service/Channel name information even though this does show up in the w_scan output.

http://www.cmason.com/tmp/tvheadend1.png
http://www.cmason.com/tmp/tvheadend2.png

(I manually renamed OPB)

While scanning is happening femon shows lock:

$ femon
FE: Auvitek AU8522 QAM/8VSB Frontend (ATSC)
status SCVYL | signal 00fa | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal 00fa | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal 00fa | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal 00fa | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal 00f0 | snr 00fa | ber 00000000 | unc 00000000 | FE_HAS_LOCK
status SCVYL | signal 00fa | snr 00f0 | ber 00000000 | unc 00000000 | FE_HAS_LOCK

When I click "Play" on any of these services (for instance same OPB service that works fine from azap) I get:

Nov 06 21:53:04 [INFO]:subscription: "HTTP" direct subscription to adapter: "Auvitek AU8522 QAM/8VSB Frontend", network: "<N/A>", mux: "195,000 kHz", provider: "<N/A>", service: "<N/A>", quality: 100
Nov 06 21:53:04 [DEBUG]:Service: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Status changed to [Hardware input]
Nov 06 21:53:04 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator
Nov 06 21:53:05 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 17 duplicate log lines suppressed
Nov 06 21:53:06 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 35 duplicate log lines suppressed
Nov 06 21:53:07 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 54 duplicate log lines suppressed
Nov 06 21:53:08 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 79 duplicate log lines suppressed
Nov 06 21:53:09 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 106 duplicate log lines suppressed
Nov 06 21:53:10 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 134 duplicate log lines suppressed
Nov 06 21:53:10 [DEBUG]:Service: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Status changed to [Hardware input] [Demuxed packets]
Nov 06 21:53:11 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 163 duplicate log lines suppressed
Nov 06 21:53:12 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 179 duplicate log lines suppressed
Nov 06 21:53:13 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 205 duplicate log lines suppressed
Nov 06 21:53:14 [WARNING]:TS: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Transport error indicator, 229 duplicate log lines suppressed
Nov 06 21:53:14 [DEBUG]:Service: Auvitek AU8522 QAM/8VSB Frontend/195,000 kHz: Status changed to [Hardware input] [Demuxed packets] [Graceperiod expired]

How can I help debug this?