Messages

Hello Forum,

my vomp-Client is in English. But if I look in the Options I see "Language: German".

I get some Config-Errors in the vompserver.log. But I don´t understand what is wrong.

In the /tmp/vompserver.log I get:

10:18:37.629339 [info]   Main - Logging started
10:18:37.629494 [debug]  UDPReplier - UDP replier started
10:18:37.629685 [info]   Main - Not starting Bootpd
10:18:37.629701 [info]   Main - Not starting TFTPd
10:18:37.629751 [debug]  MVPRelay - MVPRelay replier started
10:18:37.629772 [info]   Main - MVPRelay started
10:18:37.629812 [debug]  Main - MVPServer run success
10:19:19.881746 [debug]  UDPReplier - UDP request from 192.168.1.6
10:19:24.429380 [debug]  Client - Config dir: /var/lib/vdr/plugins/vompserver
10:19:24.429516 [debug]  Client - VompClient run success
10:19:24.429561 [debug]  RRProc - threadMethod startup
10:19:24.429585 [debug]  RRProc - threadMethod waiting
10:19:24.429612 [debug]  Client - Waiting
10:19:24.431494 [debug]  Client - Received chan=1, ser=1, op=1, edl=6
10:19:24.431523 [debug]  RRProc - recvReq set req and signalled
10:19:24.431539 [debug]  Client - Waiting
10:19:24.431548 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.431648 [debug]  Config - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
10:19:24.431686 [debug]  RRProc - written login reply len 20
10:19:24.431708 [debug]  RRProc - threadMethod waiting
10:19:24.489313 [debug]  Client - Received chan=1, ser=2, op=12, edl=17
10:19:24.489343 [debug]  RRProc - recvReq set req and signalled
10:19:24.489361 [debug]  Client - Waiting
10:19:24.489381 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.489501 [debug]  Config - Config error: Key LangCode not found
10:19:24.489522 [debug]  RRProc - Written config load failed packet
10:19:24.489565 [debug]  RRProc - threadMethod waiting
10:19:24.490561 [debug]  Client - Received chan=1, ser=3, op=33, edl=0
10:19:24.490583 [debug]  RRProc - recvReq set req and signalled
10:19:24.490601 [debug]  Client - Waiting
10:19:24.490621 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.491773 [debug]  RRProc - threadMethod waiting
10:19:24.492184 [debug]  Client - Received chan=3 kats=1229851164
10:19:24.492214 [debug]  Client - Waiting
10:19:24.493183 [debug]  Client - Received chan=1, ser=4, op=11, edl=20
10:19:24.493205 [debug]  RRProc - recvReq set req and signalled
10:19:24.493220 [debug]  Client - Waiting
10:19:24.493243 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.493266 [debug]  RRProc - Config save: General LangCode en
10:19:24.493370 [debug]  Config - Config error: Could not write temp config file
10:19:24.493405 [debug]  RRProc - threadMethod waiting
10:19:24.494184 [debug]  Client - Received chan=1, ser=5, op=34, edl=3
10:19:24.494206 [debug]  RRProc - recvReq set req and signalled
10:19:24.494224 [debug]  Client - Waiting
10:19:24.494244 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.496909 [debug]  RRProc - threadMethod waiting
10:19:24.592251 [debug]  Client - Received chan=1, ser=6, op=12, edl=30
10:19:24.592326 [debug]  RRProc - recvReq set req and signalled
10:19:24.592344 [debug]  Client - Waiting
10:19:24.592366 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.592482 [debug]  Config - Config error: Key Override Video Format not found
10:19:24.592502 [debug]  RRProc - Written config load failed packet
10:19:24.592553 [debug]  RRProc - threadMethod waiting
10:19:24.593492 [debug]  Client - Received chan=1, ser=7, op=12, edl=25
10:19:24.593521 [debug]  RRProc - recvReq set req and signalled
10:19:24.593536 [debug]  Client - Waiting
10:19:24.593556 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.593628 [debug]  Config - Config error: Key Power After Boot not found
10:19:24.593647 [debug]  RRProc - Written config load failed packet
10:19:24.593677 [debug]  RRProc - threadMethod waiting
10:19:24.594492 [debug]  Client - Received chan=1, ser=8, op=12, edl=14
10:19:24.594522 [debug]  RRProc - recvReq set req and signalled
10:19:24.594537 [debug]  Client - Waiting
10:19:24.594557 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.594631 [debug]  Config - Config error: Section TV not found
10:19:24.594648 [debug]  RRProc - Written config load failed packet
10:19:24.594714 [debug]  RRProc - threadMethod waiting
10:19:24.595493 [debug]  Client - Received chan=1, ser=9, op=12, edl=20
10:19:24.595522 [debug]  RRProc - recvReq set req and signalled
10:19:24.595540 [debug]  Client - Waiting
10:19:24.595560 [debug]  RRProc - thread woken with req, queue size: 1
10:19:24.595634 [debug]  Config - Config error: Key Remote type not found
10:19:24.595653 [debug]  RRProc - Written config load failed packet
10:19:24.595684 [debug]  RRProc - threadMethod waiting
10:19:24.596491 [debug]  Client - Received chan=1, ser=10, op=12, edl=10
--- snip --
10:19:25.123558 [debug]  Client - Waiting
10:19:25.123578 [debug]  RRProc - thread woken with req, queue size: 1
10:19:25.123600 [debug]  RRProc - Config save: General Last Power State On
10:19:25.123694 [debug]  Config - Config error: Could not write temp config file
10:19:25.123729 [debug]  RRProc - threadMethod waiting
10:19:28.975747 [debug]  Client - Received chan=1, ser=16, op=33, edl=0
10:19:28.975818 [debug]  RRProc - recvReq set req and signalled
10:19:28.975838 [debug]  Client - Waiting
10:19:28.975860 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.977054 [debug]  RRProc - threadMethod waiting
10:19:28.978474 [debug]  Client - Received chan=1, ser=17, op=12, edl=20
10:19:28.978498 [debug]  RRProc - recvReq set req and signalled
10:19:28.978516 [debug]  Client - Waiting
10:19:28.978536 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.978625 [debug]  Config - Config error: Key Remote type not found
10:19:28.978644 [debug]  RRProc - Written config load failed packet
10:19:28.978674 [debug]  RRProc - threadMethod waiting
10:19:28.980724 [debug]  Client - Received chan=1, ser=18, op=12, edl=17
10:19:28.980746 [debug]  RRProc - recvReq set req and signalled
10:19:28.980764 [debug]  Client - Waiting
10:19:28.980813 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.980888 [debug]  Config - Config error: Key LangCode not found
10:19:28.980908 [debug]  RRProc - Written config load failed packet
10:19:28.980938 [debug]  RRProc - threadMethod waiting
10:19:28.982097 [debug]  Client - Received chan=1, ser=19, op=12, edl=14
10:19:28.982118 [debug]  RRProc - recvReq set req and signalled
10:19:28.982137 [debug]  Client - Waiting
10:19:28.982157 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.982226 [debug]  Config - Config error: Section TV not found
10:19:28.982245 [debug]  RRProc - Written config load failed packet
10:19:28.982271 [debug]  RRProc - threadMethod waiting
10:19:28.983347 [debug]  Client - Received chan=1, ser=20, op=12, edl=10
10:19:28.983370 [debug]  RRProc - recvReq set req and signalled
10:19:28.983388 [debug]  Client - Waiting
10:19:28.983409 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.983481 [debug]  Config - Config error: Section TV not found
10:19:28.983500 [debug]  RRProc - Written config load failed packet
10:19:28.983530 [debug]  RRProc - threadMethod waiting
10:19:28.984597 [debug]  Client - Received chan=1, ser=21, op=12, edl=12
10:19:28.984620 [debug]  RRProc - recvReq set req and signalled
10:19:28.984638 [debug]  Client - Waiting
10:19:28.984658 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.984730 [debug]  Config - Config error: Section TV not found
10:19:28.984750 [debug]  RRProc - Written config load failed packet
10:19:28.984779 [debug]  RRProc - threadMethod waiting
10:19:28.985844 [debug]  Client - Received chan=1, ser=22, op=12, edl=25
10:19:28.985866 [debug]  RRProc - recvReq set req and signalled
10:19:28.985884 [debug]  Client - Waiting
10:19:28.985905 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.985974 [debug]  Config - Config error: Key Power After Boot not found
10:19:28.985992 [debug]  RRProc - Written config load failed packet
10:19:28.986019 [debug]  RRProc - threadMethod waiting
10:19:28.987092 [debug]  Client - Received chan=1, ser=23, op=12, edl=17
10:19:28.987114 [debug]  RRProc - recvReq set req and signalled
10:19:28.987132 [debug]  Client - Waiting
10:19:28.987152 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.987218 [debug]  Config - Config error: Key Channels not found
10:19:28.987237 [debug]  RRProc - Written config load failed packet
10:19:28.987266 [debug]  RRProc - threadMethod waiting
10:19:28.988342 [debug]  Client - Received chan=1, ser=24, op=12, edl=30
10:19:28.988364 [debug]  RRProc - recvReq set req and signalled
10:19:28.988382 [debug]  Client - Waiting
10:19:28.988402 [debug]  RRProc - thread woken with req, queue size: 1
10:19:28.988468 [debug]  Config - Config error: Key Recordings Sort Order not found
10:19:28.988487 [debug]  RRProc - Written config load failed packet
10:19:28.988516 [debug]  RRProc - threadMethod waiting
10:19:28.993164 [debug]  Client - Received chan=1, ser=25, op=12, edl=20
10:19:28.993187 [debug]  RRProc - recvReq set req and signalled
--- snip ---
10:19:33.963901 [debug]  RRProc - recvReq set req and signalled
10:19:33.963923 [debug]  RRProc - thread woken with req, queue size: 1
10:19:33.963948 [debug]  RRProc - Config save: General Remote keys H00000000I00000000K00H00000001I00000000K01H00000002I00000000K02H00000003I00000000K03H00000004I00000000K04H00000005I00000000K05H00000006I0000
0000K06H00000007I00000000K07H00000008I0000
10:19:33.964114 [debug]  Config - Config error: Could not write temp config file
10:19:33.964162 [debug]  RRProc - threadMethod waiting
10:19:33.964537 [debug]  Client - Waiting
10:19:33.965182 [debug]  Client - Received chan=1, ser=33, op=11, edl=24


Thank you!


goldfisch

Hello Portal,

now the MVP runs with VOMP.

The problem was, that the MVP was connected to the network-switch at the uplink-port of the switch.

Tank you to all who supported me to find the solution!

God bless you!


goldfisch

do you have more than on ethernet interface on your server?

No, there is only one (onboard-)Ethernet interface on the server.

could you post the output of "netstat -r"

gustav:~# netstat -r
Kernel IP Routentabelle
Ziel            Router          Genmask         Flags   MSS Fenster irtt Iface
localhost       *               255.255.255.255 UH        0 0          0 ippp5
192.168.1.0     *               255.255.255.0   U         0 0          0 eth0
default         micky           0.0.0.0         UG        0 0          0 eth0

and/or "lsdev"

gustav:~# lsdev
Device            DMA   IRQ  I/O Ports
------------------------------------------------
(0)                     209
(1)                     169
(2)                     201
0000:00:02.0                 1000-1007
0000:00:1d.0                 1400-141f
0000:00:1d.1                 1800-181f
0000:00:1d.2                 1c00-1c1f
0000:00:1d.3                 2000-201f
0000:00:1f.0                 f000-f07f f180-f1bf
0000:00:1f.1                 3000-300f
0000:00:1f.2                 3010-301f 3020-3023 3024-3027 3028-302f 3030-3037
0000:00:1f.3                 2400-241f
0000:00:1f.5                 2800-283f 2c00-2cff
0000:02:01.0                   4000-401f
0000:03:0f.0                   5000-501f   5400-543f
acpi                      9
ACPI                             f000-f003     f004-f005     f008-f00b     f010-f015     f028-f02f
b1pci-5000              193      5000-501e
cascade             4
dma                          0080-008f
dma1                         0000-001f
dma2                         00c0-00df
e1000                            4000-401f
floppy              2     6  03f2-03f5 03f7-03f7
fpu                          00f0-00ff
i801_smbus                     2400-241f
i8042                     1
ide0                           3000-3007
ide1                     15  0170-0177 0376-0376   3008-300f
Intel                          2800-283f   2c00-2cff
keyboard                     0060-006f
libata                         3010-301f   3020-3023   3024-3027   3028-302f   3030-3037
motherboard                    f000-f07f f100-f10f   f180-f1bf f820-f82f fe00-fe00
parport0            3     7  0378-037a 0778-077a
PCI                          0cf8-0cff 4000-4fff 5000-5fff
pic1                         0020-0021
pic2                         00a0-00a1
rtc                       8  0070-0077
serial                       02e8-02ef 02f8-02ff 03f8-03ff
timer                     0
timer0                       0040-0043
timer1                       0050-0053
uhci_hcd                       1400-141f   1800-181f   1c00-1c1f   2000-201f
uhci_hcd:usb2           185
uhci_hcd:usb4           177
vga+                         03c0-03df

please also tell us what's the hardware version of your MVP ?

Model 86002 Rev D3A Lot 4203

I am stumped.

Could someone help me?

Tanks

goldfisch

1.
complete the vomp(.*)?.conf files adequately
have a look at the examples in the server source
http://www.loggytronic.com/dl/vomp-dongle-0.3.0
there you'll find a sample for MVP specific settings (vomp-00-00-00-00-00-00.conf.sample)
and global settings (vomp.conf.sample)

and/or 2.
for testing, please deactivate the tftp server in inetd.conf
and use the vomp-built-in server stuff to determine what's wrong.
(this way, you also get all log data from one file)

cheers

Hello,

I reconfigured the vompserver copletly new. Tftp in inetd.conf is disabled.

But it doesn´t runs. I get only two white balks on the TV. But I don´t understand why.

In the /tmp/vompserver.log I get:

22:08:55.947995 [info]   Main - Logging started
22:08:55.948137 [debug]  UDPReplier - UDP replier started
22:08:55.948317 [debug]  BOOTPD - Starting bootpd
22:08:55.948361 [debug]  BOOTPD - Bootp replier started
22:08:55.948448 [info]   Main - TFTP path '/usr/share/vdr-plugin-vompserver/'
22:08:55.948468 [debug]  Tftpd - Starting TFTPd
22:08:55.948510 [debug]  Tftpd - TFTP server started with base path '/usr/share/vdr-plugin-vompserver/'
22:08:55.948765 [debug]  MVPRelay - MVPRelay replier started
22:08:55.948791 [info]   Main - MVPRelay started
22:08:55.948830 [debug]  Main - MVPServer run success
22:08:56.150091 [debug]  BOOTPD - Starting wait
22:08:56.150135 [debug]  Tftpd - Starting wait
22:09:07.588259 [debug]  BOOTPD - Wait finished
22:09:07.588328 [debug]  BOOTPD - Got request
22:09:07.588382 [debug]  Config - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
22:09:07.588400 [debug]  BOOTPD - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
22:09:07.588484 [debug]  BOOTPD - Found IP 192.168.1.6 for MVP
22:09:07.588546 [debug]  BOOTPD - Will enforce IP 192.168.1.6 on MVP even if it already has another
22:09:07.588565 [debug]  BOOTPD - Giving MVP IP from config
22:09:07.588674 [debug]  BOOTPD - Starting wait
22:09:16.608339 [debug]  BOOTPD - Wait finished
22:09:16.608433 [debug]  BOOTPD - Got request
22:09:16.608502 [debug]  Config - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
22:09:16.608520 [debug]  BOOTPD - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
22:09:16.608615 [debug]  BOOTPD - Found IP 192.168.1.6 for MVP
22:09:16.608674 [debug]  BOOTPD - Will enforce IP 192.168.1.6 on MVP even if it already has another
22:09:16.608693 [debug]  BOOTPD - Giving MVP IP from config
22:09:16.608813 [debug]  BOOTPD - Starting wait
...


/etc/vdr/plugins/vomp.conf
[General]

## Specify a log file here to enable logging

Log file = /tmp/vompserver.log

## If you have more than one vompserver running you
## can enter a name here that will appear on the
## server select list on the MVP

Server name = gustav.duck

## Enable this to start the built in Bootp server enabled = yes
## Required to boot the MVP if you have not got a
## DHCP server that can tell the MVP its boot file
## name and server

Bootp server enabled = yes

## Enable this to start the built in TFTP server
## Required to boot the MVP if you have not got a
## TFTP server running elsewhere

TFTP server enabled = yes

## Base directory for TFTP server
## If you leave this blank the plugin config
## path will be used - i.e. where this file is

TFTP directory = /usr/share/vdr-plugin-vompserver

## Change the following to "no" to disable the
### built in mvprelay server

MVPRelay enabled = yes


/var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
[Boot]

## If you enable the bootp server in vomp.conf, you can specify
## an IP to give out to this MVP here.

IP = 192.168.1.6

## If you have a router that gives out addresses but you want
## the above address forced to the MVP, set this:

Override IP = 1

## A file name to send to the MVP. The MVP will then request
## this file from the TFTP server. Don't use a path here!

TFTP file name = vomp-dongle

[General]

## The client auto-detects whether to use PAL or NTSC. If you
## want to force the other one, set it here. Beware of popping
## your TV with this one!

# Override Video Format = NTSC

What is wrong?

Thanks


goldfisch

Hello Portal,

in /tmp/vompserver.log I get the error-messages:

23:54:25.564739 [debug]  Config - Config error: Section Boot not found
23:54:49.206987 [debug]  Config - Config error: Key Server name not found

Can someone explain me this messages?

Thank you!

goldfisch



Here is the full log:

23:54:25.564554 [debug]  BOOTPD - Got request
23:54:25.564608 [debug]  Config - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
23:54:25.564624 [debug]  BOOTPD - Opened config file: /var/lib/vdr/plugins/vompserver/vomp-00-0D-FE-00-23-89.conf
23:54:25.564739 [debug]  Config - Config error: Section Boot not found
23:54:25.564760 [WARN]   BOOTPD - No IP found for MVP. Hopefully it has one already...
23:54:25.564832 [debug]  Config - Config error: Section Boot not found
23:54:25.564851 [debug]  BOOTPD - Will not change MVP IP if it already has one
23:54:25.564866 [debug]  BOOTPD - No IP found to give to MVP
23:54:25.564884 [debug]  BOOTPD - Starting wait
23:54:41.995731 [info]   Main - Stopped main server thread
23:54:49.206899 [info]   Main - Logging started
23:54:49.206987 [debug]  Config - Config error: Key Server name not found
23:54:49.207055 [debug]  UDPReplier - UDP replier started
23:54:49.207219 [debug]  Config - Config error: Key MVPRelay enabled not found
23:54:49.207239 [info]   Main - Not starting Bootpd
23:54:49.207278 [info]   Main - Not starting TFTPd
23:54:49.407761 [debug]  MVPRelay - MVPRelay replier started
23:54:49.407821 [info]   Main - MVPRelay started
23:54:49.407889 [debug]  Main - MVPServer run success
00:03:12.241975 [info]   Main - Logging started
00:03:12.242065 [debug]  Config - Config error: Key Server name not found
00:03:12.442773 [debug]  UDPReplier - UDP replier started
00:03:12.443014 [debug]  Config - Config error: Key MVPRelay enabled not found
00:03:12.443036 [info]   Main - Not starting Bootpd
00:03:12.443054 [info]   Main - Not starting TFTPd
00:03:12.443128 [debug]  MVPRelay - MVPRelay replier started
00:03:12.443152 [info]   Main - MVPRelay started
00:03:12.443185 [debug]  Main - MVPServer run success
00:14:14.616677 [info]   Main - Logging started
00:14:14.616768 [debug]  Config - Config error: Key Server name not found
00:14:14.616837 [debug]  UDPReplier - UDP replier started
00:14:14.617008 [debug]  Config - Config error: Key MVPRelay enabled not found
00:14:14.617027 [info]   Main - Not starting Bootpd
00:14:14.617042 [info]   Main - Not starting TFTPd
00:14:14.617088 [debug]  MVPRelay - MVPRelay replier started
00:14:14.617108 [info]   Main - MVPRelay started
00:14:14.617144 [debug]  Main - MVPServer run success
00:25:15.806712 [info]   Main - Logging started
00:25:15.806812 [debug]  Config - Config error: Key Server name not found
00:25:16.006611 [debug]  UDPReplier - UDP replier started
00:25:16.006881 [debug]  Config - Config error: Key MVPRelay enabled not found
00:25:16.006902 [info]   Main - Not starting Bootpd
00:25:16.006921 [info]   Main - Not starting TFTPd
00:25:16.007005 [debug]  MVPRelay - MVPRelay replier started
00:25:16.007026 [info]   Main - MVPRelay started
00:25:16.007064 [debug]  Main - MVPServer run success

In my distro (Mandriva) there is a file /etc/iftab which has the MAC address of each ethernet card in the system. If you change network cards the OS sees the different MAC address and allocates a new ethx device to it. Simply deleting this file allows the system to 'forget' the old network card and start again from eth0.

I use debian. There I have no /etc/iftab. With the Posting from Harry I changed the name of the device to "eth0". But the MVP doesn´t run.

Any idea?

Thank you!


goldfisch

Is you server "gustav" still on IP 192.168.1.200 ?

Yes.

As root, run the command

Code Select Expand

lsof | grep UDP

Post back your results.

gustav:/var/lib/vdr/plugins/vompserver# lsof | grep UDP
dnsmasq    5699     dnsmasq    4u     IPv4       8339                  UDP *:domain
dnsmasq    5699     dnsmasq    6u     IPv6       8341                  UDP *:domain
cupsd      6213        root    4u     IPv4       9059                  UDP *:ipp
inetd      8668        root    6u     IPv4      12872                  UDP *:discard
inetd      8668        root   11u     IPv4      12882                  UDP *:tftp
nmbd       8683        root    9u     IPv4      12953                  UDP *:netbios-ns
nmbd       8683        root   10u     IPv4      12954                  UDP *:netbios-dgm
nmbd       8683        root   11u     IPv4      12956                  UDP gustav.duck:netbios-ns
nmbd       8683        root   12u     IPv4      12957                  UDP gustav.duck:netbios-dgm
portmap    9064      daemon    4u     IPv4      13650                  UDP *:sunrpc
rpc.mount  9281        root    6u     IPv4      14246                  UDP *:973
rpc.statd  9306       statd    3u     IPv4      14355                  UDP *:32775
rpc.statd  9306       statd    6u     IPv4      14310                  UDP *:1002
dhcpd3     9350        root    5u     IPv4      14618                  UDP *:bootps
vdr       18161         vdr  130u     IPv4     148586                  UDP *:3024
vdr       18161         vdr  134u     IPv4     148589                  UDP *:16881


So I think the tftp-Server is running.

From the same server I boot a diskless Print-Server. If the print-Server starts I get a messega in /var/log/syslog

...
Dec  8 23:17:56 gustav mountd[9281]: authenticated mount request from print1:1023 for /tftpboot/print1 (/tftpboot/print1)
...

But form the MVP I get in /var/log/syslog only:

...
Dec  8 23:42:28 gustav dhcpd: DHCPDISCOVER from 00:0d:fe:00:23:89 via eth0
Dec  8 23:42:28 gustav dhcpd: DHCPOFFER on 192.168.1.6 to 00:0d:fe:00:23:89 via eth0
...

The dongle.bin is in /tftpboot

gustav:/var/lib/vdr/plugins/vompserver# ls /tftpboot/ -la
insgesamt 1840
drwxr-xr-x  6 root    root     4096 2008-08-01 22:34 .
drwxr-xr-x 27 root    root     4096 2008-12-08 23:41 ..
-rw-r--r--  1 gerhard users 1841184 2008-08-01 22:00 dongle.bin
drwxr-xr-x 22 root    root     4096 2008-12-02 21:07 print1
-rw-r--r--  1 root    root    11830 2007-03-09 14:39 pxelinux.0
drwxr-xr-x  2 root    root     4096 2007-04-09 20:55 pxelinux.cfg
drwxr-xr-x 22 root    root     4096 2006-01-27 14:42 router1
drwxr-xr-x 22 root    root     4096 2008-12-02 21:07 vdr2

if you're using udev, you should be able to set the dev name somewhere underneath
/etc/udev/rules.d.
udev uses its own rule syntax. it's explained here: http://www.reactivated.net/udevrules.php

Thank you for that tip. Now my network device runs as "eth0".

But the MVP doesn´t run. I get only two white balks.

Any Idea?

goldfisch

german: http://www.vdr-portal.de/board/thread.php?threadid=82462

Hello Forum,

I changed my VDR-Computer. After that my MVP-Client doesn´t run. If I start my MVP, I get the blue start screen. But I think the dongle.bin wasn´t load. VOMP for Windows runs with the new VDR-Computer.

In my VDR-Server I see in /var/log/syslog:

Dec  7 23:15:50 gustav dhcpd: DHCPDISCOVER from 00:0d:fe:00:23:89 via eth2
Dec  7 23:15:50 gustav dhcpd: DHCPOFFER on 192.168.1.6 to 00:0d:fe:00:23:89 via

On my old VDR-Computer I had a network-device that runs as "eth0". In my new Computer the network-device runs as "eth2".

/etc/dhcp3/dhcpd.conf

...
host mvp {
  hardware ethernet 00:0D:FE:00:23:89;
  fixed-address 192.168.1.6;
  server-name "gustav.duck";
  filename "dongle.bin";
  next-server 192.168.1.200;
  option subnet-mask 255.255.255.0;
  option broadcast-address 192.168.1.0;
}
...

/etc/inet.conf
...
tftp           dgram   udp     wait    root  /usr/sbin/in.tftpd /usr/sbin/in.tftpd -s /tftpboot
...


gustav:/tftpboot# ls /tftpboot/
dongle.bin  print1  pxelinux.0  pxelinux.cfg  router1  vdr2


/etc/vdr/plugins/vomp.conf
[General]

## Specify a log file here to enable logging

Log file = /tmp/vompserver.log

## If you have more than one vompserver running you
## can enter a name here that will appear on the
## server select list on the MVP

# Server name = My-Server


## Enable this to start the built in Bootp server enabled = no
## Required to boot the MVP if you have not got a
## DHCP server that can tell the MVP its boot file
## name and server

Bootp server enabled = no

## Enable this to start the built in TFTP server
## Required to boot the MVP if you have not got a
## TFTP server running elsewhere

TFTP server enabled = no

## Base directory for TFTP server
## If you leave this blank the plugin config
## path will be used - i.e. where this file is

TFTP directory = /usr/share/vdr-plugin-vompserver

[Media]
Dir.1=/home/userpictures
Dir.2=/home/usermusic
Dir.3=/home/uservideo
#you can add up to Dir.10...

/tmp/vomp.log

00:09:38.636727 [info]   Main - Logging started
00:09:38.707354 [debug]  Config - Config error: Key Server name not found
00:09:38.707474 [debug]  UDPReplier - UDP replier started
00:09:38.707660 [debug]  Config - Config error: Key MVPRelay enabled not found
00:09:38.707680 [info]   Main - Not starting Bootpd
00:09:38.707696 [info]   Main - Not starting TFTPd
00:09:38.707748 [debug]  MVPRelay - MVPRelay replier started
00:09:38.707772 [info]   Main - MVPRelay started
00:09:38.707806 [debug]  Main - MVPServer run success



What´s wrong in my configuration?

I think the change from eth0 to eth2 is the problem. How can I change the device name?

Thank you!


goldfisch

@MartenR:
The Problem exists on all tv stations. It also exists on recordings.

The audio playes a long time.

@the "PTS JUMP" messages keep happening as long as the audio is playing.

Thank you!

Gerhard

Hi Torsten,

I can´t watch recordings. I get only audio, but no video.

telnet is a good idea.

I tried and I got this messages:

21:03:20.390452 [debug]  View - Destruct, now 4
21:03:20.391095 [debug]  Box - Destruct, now 9
21:03:20.435305 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:20.478199 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:20.601217 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:21.274414 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:21.436637 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:21.498981 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:22.159331 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:22.340947 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:22.885178 [debug]  TS Demuxer - Packet overflow, pid 101
21:03:23.067913 [debug]  TS Demuxer - Packet overflow, pid 101
...

If I try to see recordings I get:

21:20:49.141211 [debug]  Demuxer - STILL USING OLD REF
21:20:49.141479 [debug]  VVideoRec - 0:00:03 / 0:51:40
21:20:49.156340 [debug]  Timers - Starting set timer 1
21:20:49.156832 [debug]  Timers - Timer set for 0x101373b4 ref 2
21:20:49.284032 [debug]  Demuxer - +* PTS JUMP *+ 1384710496 288
21:20:49.358804 [debug]  Timers - Timer firing for client 0x101373b4 ref 2
21:20:49.359283 [notice] Core - Signal 23 received
21:20:49.359522 [debug]  Core - SIGURG caught
21:20:49.359741 [debug]  Command - processing message 15
21:20:49.359950 [debug]  Command - sending timer to 0x101373b4 with parameter 2
21:20:49.360162 [debug]  VVideoRec - Draw bar clocks
21:20:49.361789 [debug]  Demuxer - STILL USING OLD REF
21:20:49.362057 [debug]  VVideoRec - 0:00:03 / 0:51:40
21:20:49.377132 [debug]  Timers - Starting set timer 1
21:20:49.377626 [debug]  Timers - Timer set for 0x101373b4 ref 2
21:20:49.398629 [debug]  Timers - Timer firing for client 0x101373b4 ref 1
21:20:49.399110 [notice] Core - Signal 23 received
21:20:49.399349 [debug]  Core - SIGURG caught
21:20:49.399567 [debug]  Command - processing message 15
21:20:49.399776 [debug]  Command - sending timer to 0x101373b4 with parameter 1
21:20:49.399989 [debug]  Timers - Starting cancel timer 0x101373b4 2, list size
= 2
21:20:49.400224 [debug]  Timers - Removed timer for 0x101373b4 ref 2
21:20:49.611863 [debug]  Demuxer - +* PTS JUMP *+ 1384796896 312
21:20:49.907042 [debug]  Demuxer - +* PTS JUMP *+ 1384883296 336
21:20:50.195769 [debug]  Demuxer - +* PTS JUMP *+ 1384969696 360
21:20:50.391498 [debug]  Demuxer - +* PTS JUMP *+ 1385056096 384
21:20:50.653686 [debug]  Demuxer - +* PTS JUMP *+ 1385142496 408
21:20:50.902165 [debug]  Demuxer - +* PTS JUMP *+ 1385228896 432
21:20:51.137629 [debug]  Demuxer - +* PTS JUMP *+ 1385315296 456


What should that tell me?

Thank you!

Gerhard

TAre you sure the right vdr-plugin AND the right dongle is loaded?

apt-show-versions tells me:

gustav:/tftpboot# apt-show-versions vdr-plugin-vompserver
vdr-plugin-vompserver/experimental uptodate 0.2.6-5

gustav:/tftpboot# apt-show-versions vdr-vompclient-mvp
vdr-vompclient-mvp/experimental uptodate 0.2.6-1

Are these different versions?

Additional I tried the dongle.bin from http://www.loggytronic.com/dl/vomp-dongle-0.2.6 but I get no video - only audio.

Thank you!

Gerhard

could also have a look in /var/log/syslog to check whether the problem is on the server side.

I can´t see any Problems:

Jul 23 23:28:08 gustav vdr: [27681] starting plugin: vompserver
Jul 23 23:28:08 gustav vdr: [27681] VOMP: Config file found
Jul 23 23:28:08 gustav vdr: [27703] EPGSearch: conflictcheck thread started (pid=27681, tid=27703)
Jul 23 23:28:08 gustav vdr: [27704] burn-chain manager thread started (pid=27681, tid=27704)
Jul 23 23:28:08 gustav vdr: [27705] [control] gateways thread started (pid=27681)
Jul 23 23:28:08 gustav vdr: [27705] [control] socket opened
Jul 23 23:28:08 gustav vdr: [27706] streamdev server thread started (pid=27681, tid=27706)
Jul 23 23:28:08 gustav vdr: [27706] Streamdev: Listening (VTP) on port 2004
Jul 23 23:28:08 gustav vdr: [27706] Streamdev: Listening (HTTP) on port 3000
Jul 23 23:28:08 gustav vdr: [27681] ERROR: /dev/lircd: No such file or directory
Jul 23 23:28:08 gustav vdr: [27681] ERROR: remote control LIRC not ready!
Jul 23 23:28:08 gustav vdr: [27681] switching to channel 174
Jul 23 23:28:08 gustav vdr: [27681] setting watchdog timer to 60 seconds
Jul 23 23:28:08 gustav vdr: [27681] ERROR: no OSD provider available - using dummy OSD!
Jul 23 23:28:08 gustav vdr: [27681] live reloading timers
Jul 23 23:28:19 gustav vdr: [27681] max. latency time 1 seconds
Jul 23 23:28:26 gustav identd[27818]: started
Jul 23 23:28:28 gustav vdr: [27703] EPGSearch: timer conflict check started
Jul 23 23:28:28 gustav vdr: [27703] EPGSearch: timer conflict check finished
Jul 23 23:28:28 gustav vdr: [27702] EPGSearch: search timer update started
Jul 23 23:28:29 gustav dhcpd: DHCPDISCOVER from 00:0d:fe:00:23:89 via eth0
Jul 23 23:28:29 gustav dhcpd: DHCPOFFER on 192.168.1.6 to 00:0d:fe:00:23:89 via eth0
Jul 23 23:28:29 gustav fetchmail[5746]: Mo 23 Jul 2007 23:28:29 CEST: schlafe 300 Sekunden lang
Jul 23 23:28:34 gustav vdr: [27702] EPGSearch: search timer update finished
Jul 23 23:28:48 gustav dhcpd: DHCPDISCOVER from 00:0d:fe:00:23:89 via eth0
Jul 23 23:28:48 gustav dhcpd: DHCPOFFER on 192.168.1.6 to 00:0d:fe:00:23:89 via eth0
Jul 23 23:28:48 gustav dhcpd: DHCPREQUEST for 192.168.1.6 (192.168.1.200) from 00:0d:fe:00:23:89 via eth0
Jul 23 23:28:48 gustav dhcpd: DHCPACK on 192.168.1.6 to 00:0d:fe:00:23:89 via eth0
Jul 23 23:28:57 gustav vdr: [28014] ERROR: plugin '<no name given>' called cPlugin::ConfigDirectory(), which is not thread safe!
Jul 23 23:29:01 gustav vdr: [28020] receiver on device 2 thread started (pid=27681, tid=28020)
Jul 23 23:29:01 gustav vdr: [28021] TS buffer on device 2 thread started (pid=27681, tid=28021)

/tmp/vompserver.log

23:28:02.720630 [info]   Main - Stopped main server thread
23:28:08.931752 [info]   Main - Logging started
23:28:08.931843 [debug]  Config - 2
23:28:08.931871 [debug]  Config - Config error: Key Server name not found
23:28:08.931950 [debug]  UDPReplier - UDP replier started
23:28:08.932071 [info]   Main - Not starting Bootpd
23:28:08.932091 [info]   Main - Not starting TFTPd
23:28:08.932139 [debug]  MVPRelay - MVPRelay replier started
23:28:08.932161 [info]   Main - MVPRelay started
23:28:08.933502 [debug]  Main - MVPServer run success
23:28:52.801467 [debug]  UDPReplier - UDP request from 192.168.1.6
23:28:57.322297 [debug]  Client - MVPClient run success
23:28:57.322456 [debug]  TCP - SO_KEEPALIVE = 0
23:28:57.322547 [debug]  TCP - TCP_KEEPIDLE = 0
23:28:57.322569 [debug]  TCP - TCP_KEEPINTVL = 0
23:28:57.322588 [debug]  TCP - TCP_KEEPCNT = 0
23:28:57.322609 [debug]  Client - Waiting
23:28:57.322641 [debug]  Client - Received packet, length = 10
23:28:57.322660 [debug]  Client - SwitchOp
23:28:57.322949 [debug]  Config - Opened config file: /var/lib/vdr/plugins/vomp-00-0D-FE-00-23-89.conf
23:28:57.323002 [debug]  Client - written login reply
23:28:57.323028 [debug]  Client - Waiting
23:28:57.362129 [debug]  Client - Received packet, length = 21
23:28:57.362155 [debug]  Client - SwitchOp
23:28:57.362340 [debug]  Client - Written config load packet
23:28:57.362370 [debug]  Client - Waiting
23:28:57.434632 [debug]  Client - Received packet, length = 34
23:28:57.434718 [debug]  Client - SwitchOp
23:28:57.434844 [debug]  Config - Config error: Key Override Video Format not found
23:28:57.434898 [debug]  Client - Written config load failed packet
23:28:57.434926 [debug]  Client - Waiting
23:28:57.435580 [debug]  Client - Received packet, length = 29
23:28:57.435601 [debug]  Client - SwitchOp
23:28:57.435654 [debug]  Config - Config error: Key Power After Boot not found
23:28:57.435690 [debug]  Client - Written config load failed packet
23:28:57.435713 [debug]  Client - Waiting
23:28:57.436162 [debug]  Client - Received packet, length = 18
23:28:57.436182 [debug]  Client - SwitchOp
23:28:57.436256 [debug]  Client - Written config load packet
23:28:57.436284 [debug]  Client - Waiting
23:28:57.436754 [debug]  Client - Received packet, length = 24
23:28:57.436774 [debug]  Client - SwitchOp
23:28:57.436825 [debug]  Config - Config error: Key Remote type not found
23:28:57.436857 [debug]  Client - Written config load failed packet
23:28:57.436881 [debug]  Client - Waiting
23:28:57.437320 [debug]  Client - Received packet, length = 14
23:28:57.437340 [debug]  Client - SwitchOp
23:28:57.437387 [debug]  Config - 2
23:28:57.437415 [debug]  Config - Config error: Key Aspect not found
23:28:57.437447 [debug]  Client - Written config load failed packet
23:28:57.437470 [debug]  Client - Waiting
23:28:57.931833 [debug]  Client - Received packet, length = 16
23:28:57.931884 [debug]  Client - SwitchOp
23:28:57.932032 [debug]  Client - Written config load packet
23:28:57.932062 [debug]  Client - Waiting
23:28:57.932668 [debug]  Client - Received packet, length = 32
23:28:57.932689 [debug]  Client - SwitchOp
23:28:57.932739 [debug]  Config - 2
23:28:57.932768 [debug]  Config - Config error: Section Advanced not found
23:28:57.932800 [debug]  Client - Written config load failed packet
23:28:57.932824 [debug]  Client - Waiting
23:28:57.933286 [debug]  Client - Received packet, length = 32
23:28:57.933306 [debug]  Client - SwitchOp
23:28:57.933323 [debug]  Client - Config save: General Last Power State On
23:28:57.933701 [debug]  Client - written ULONG 1
23:28:57.933735 [debug]  Client - Waiting
23:29:00.304735 [debug]  Client - Received packet, length = 4
23:29:00.304843 [debug]  Client - SwitchOp
23:29:00.304995 [debug]  Config - Config error: Key Channels not found
23:29:00.305020 [debug]  Client - name: 'Das Erste'
23:29:00.305039 [debug]  Client - name: 'ZDF'
23:29:00.305056 [debug]  Client - name: 'Bayerisches FS'
23:29:00.305072 [debug]  Client - name: 'DAS VIERTE'
23:29:00.305090 [debug]  Client - name: 'Bibel TV'
...
23:29:01.589979 [debug]  Client - SwitchOp
23:29:01.590150 [debug]  Client - Written config load packet
23:29:01.590182 [debug]  Client - Waiting
23:29:01.753007 [debug]  Client - Received packet, length = 4
23:29:01.753044 [debug]  Client - SwitchOp
23:29:01.753062 [debug]  Client - STOP STREAMING RECEIVED
23:29:01.753096 [debug]  Client - written ULONG 1
23:29:01.753122 [debug]  Client - Waiting
23:29:01.754846 [debug]  Client - Received packet, length = 8
23:29:01.754868 [debug]  Client - SwitchOp
23:29:01.754899 [debug]  Client - length = 4
23:29:01.754919 [debug]  Client - Looking for channel 1::: number: 1 name: 'Das Erste'
23:29:01.754937 [debug]  Client - Found channel number 1, vpid = 101, apid1 = 102
23:29:01.755035 [debug]  Client - Config: Live TV priority: 1
23:29:01.755057 [debug]  Client - Using live TV priority 1
23:29:01.755083 [debug]  MVPReceiver - Channel has VPID 101 APID 102
23:29:01.755293 [debug]  MVPReceiver - VDR active
23:29:01.755388 [debug]  Client - written ULONG 1
23:29:01.755419 [debug]  Client - Waiting
23:29:01.769338 [debug]  Client - Received packet, length = 16
23:29:01.769386 [debug]  Client - SwitchOp
23:29:01.769406 [debug]  Client - get schedule called for channel 1
23:29:01.769427 [debug]  Client - Looking for channel 1::: number: 1 name: 'Das Erste'
23:29:01.769445 [debug]  Client - Found channel number 1, vpid = 101, apid1 = 102
23:29:01.769461 [debug]  Client - Got channel
23:29:01.769480 [debug]  Client - Got schedule!s! object
23:29:01.769499 [debug]  Client - Got schedule object
23:29:01.769527 [debug]  Client - Got an event object 0x825e930
23:29:01.769547 [debug]  Client - Done s1
23:29:01.769563 [debug]  Client - Done s2
23:29:01.769581 [debug]  Client - Done s3 232
23:29:01.769597 [debug]  Client - Got an event object 0x826caa8
23:29:01.769615 [debug]  Client - Done s1
23:29:01.769631 [debug]  Client - Done s2
23:29:01.769647 [debug]  Client - Done s3 473
23:29:01.769664 [debug]  Client - Got an event object 0x826ccb8
23:29:01.769682 [debug]  Client - Done s1
23:29:01.769698 [debug]  Client - Done s2
23:29:01.769715 [debug]  Client - Done s3 734
23:29:01.769731 [debug]  Client - Got an event object 0x826cf08
23:29:01.769748 [debug]  Client - Done s1
23:29:01.769765 [debug]  Client - Done s2
23:29:01.769780 [debug]  Client - Done s3 759
23:29:01.769797 [debug]  Client - Got an event object 0x826d038
23:29:01.769815 [debug]  Client - Done s1
23:29:01.769831 [debug]  Client - Done s2
23:29:01.769848 [debug]  Client - Done s3 1061
...

My Hardware:

MediaMVP
* Model 86002 Rev D3A Lot4203
* Software Rev. 1.0 (steht auf dem Startbildschirm)

VDR:
* AMD Athlon(tm) XP 2000+
* 512 MB RAM
gustav:/tftpboot# lspci
00:00.0 Host bridge: VIA Technologies, Inc. VT8366/A/7 [Apollo KT266/A/333]
00:01.0 PCI bridge: VIA Technologies, Inc. VT8366/A/7 [Apollo KT266/A/333 AGP]
00:08.0 Network controller: AVM Audiovisuelles MKTG & Computer System GmbH B1 ISDN
00:09.0 Multimedia controller: Philips Semiconductors SAA7146 (rev 01)
00:0a.0 Multimedia controller: Philips Semiconductors SAA7146 (rev 01)
00:0b.0 Multimedia controller: Philips Semiconductors SAA7146 (rev 01)
00:0c.0 Ethernet controller: D-Link System Inc DGE-528T Gigabit Ethernet Adapter (rev 10)
00:11.0 ISA bridge: VIA Technologies, Inc. VT8233A ISA Bridge
00:11.1 IDE interface: VIA Technologies, Inc. VT82C586A/B/VT82C686/A/B/VT823x/A/C PIPC Bus Master IDE (rev 06)
00:11.2 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1 Controller (rev 23)
00:11.3 USB Controller: VIA Technologies, Inc. VT82xxxxx UHCI USB 1.1 Controller (rev 23)
01:00.0 VGA compatible controller: Matrox Graphics, Inc. MGA G200 AGP (rev 03)

gustav:/tftpboot# ctvdrinfo
**************************************
*        c't VDR Übersicht           *
**************************************

c't VDR: 1.4.7-2ctvdr1
Kernel : 2.6.18

Patches:
--------------------------------------
liemikuutio
jumpplay
subtitles-ttxtsubs
submenu
disableDoubleEpgEntrys
noepg
wareagle-icons
rotor
yaepg
sourcecaps
pin
graphtft-0.1
cuttime

Plugins (APIVERSION 1.4.5):
( N = Native Plugin       )
( ! = Falscher Patchlevel )
( - = Deaktiviert         )
--------------------------------------
  vdr-plugin-burn (0.1.0~pre21-13)
  vdr-plugin-control (0.0.2a-27)
  vdr-plugin-epgsearch (0.9.22-2) conflictcheckonly
  vdr-plugin-epgsearch (0.9.22-2)
  vdr-plugin-epgsearch (0.9.22-2) epgsearchonly
  vdr-plugin-epgsearch (0.9.22-2) quickepgsearch
  vdr-plugin-femon (1.1.3-1)
  vdr-plugin-live (0.1.0-4)
  vdr-plugin-streamdev-server (0.3.3~cvs20070509-2)
  vdr-plugin-svdrpext (0.0.1-5)
  vdr-plugin-undelete (0.0.6-14)
  vdr-plugin-vdrrip (0.3.0-33)
  vdr-plugin-vompserver (0.2.6-5)

Addon­Packages:
--------------------------------------
vdr-addon-acpiwakeup (0.0.5)
vdr-addon-noad (0.6.0-8)
vdr-addon-tvmovie2vdr (0.5.14-1)
vdr-addon-vdrconvert (0.1.1-16)
vdr-burnbackgrounds (0.0.1-4)
vdr-genindex (0.1.3-1)
vdr-vompclient-mvp (0.2.6-1)
vdr-xxv (0.80+svn20061130-1)
vdr-xxv-skins (1.1.4)

Hi Fabian,

thank you for your answer.

I tried with Prio=1, 20 and 40. But I can only hear audio and don´t see video.

Do you have another tipp for me?

Thank you!

Gerhard

Here is the /tmp/vomperver.log - I can not see something wrong.

21:33:58.067944 [debug]  MVPReceiver - Channel has VPID 767 APID 768
21:33:58.068173 [debug]  MVPReceiver - VDR active
21:33:58.068261 [debug]  Client - written ULONG 1
21:33:58.068293 [debug]  Client - Waiting
21:33:58.081929 [debug]  Client - Received packet, length = 16
21:33:58.082006 [debug]  Client - SwitchOp
21:33:58.082026 [debug]  Client - get schedule called for channel 6
21:33:58.082046 [debug]  Client - Looking for channel 6::: number: 1 name: 'Das Erste'
21:33:58.082064 [debug]  Client - Looking for channel 6::: number: 2 name: 'ZDF'
21:33:58.082082 [debug]  Client - Looking for channel 6::: number: 3 name: 'Bayerisches FS'
21:33:58.082098 [debug]  Client - Looking for channel 6::: number: 4 name: 'DAS VIERTE'
21:33:58.082114 [debug]  Client - Looking for channel 6::: number: 5 name: 'Bibel TV'
21:33:58.082131 [debug]  Client - Looking for channel 6::: number: 6 name: 'KABEL 1'
21:33:58.082147 [debug]  Client - Found channel number 6, vpid = 767, apid1 = 768
21:33:58.082164 [debug]  Client - Got channel
21:33:58.082181 [debug]  Client - Got schedule!s! object
21:33:58.082241 [debug]  Client - Got schedule object
21:33:58.082264 [debug]  Client - Got an event object 0x89dc828
21:33:58.082283 [debug]  Client - Got an event object 0x82e4a10
21:33:58.082300 [debug]  Client - Got an event object 0x89765d8
21:33:58.082317 [debug]  Client - Got an event object 0x8985af0
21:33:58.082342 [debug]  Client - Got an event object 0x893a9f8
21:33:58.082361 [debug]  Client - Got an event object 0x890f2a0
21:33:58.082377 [debug]  Client - Got an event object 0x89717e8
21:33:58.082394 [debug]  Client - Got an event object 0x833d218
21:33:58.082411 [debug]  Client - Got an event object 0x82f8798
21:33:58.082427 [debug]  Client - Got an event object 0x83376e8
21:33:58.082443 [debug]  Client - Got an event object 0x8867f18
21:33:58.082460 [debug]  Client - Got an event object 0x8957150
21:33:58.082476 [debug]  Client - Got an event object 0x8369a40
21:33:58.082492 [debug]  Client - Got an event object 0x88b20a0
21:33:58.082508 [debug]  Client - Got an event object 0x883dcd0
21:33:58.082525 [debug]  Client - Got an event object 0x883d438
21:33:58.082541 [debug]  Client - Got an event object 0x834a830
21:33:58.082612 [debug]  Client - Got an event object 0x882c418
21:33:58.082631 [debug]  Client - Got an event object 0x896dc90
21:33:58.082648 [debug]  Client - Got an event object 0x882fdb8
21:33:58.082665 [debug]  Client - Got an event object 0x84f34b0
21:33:58.082682 [debug]  Client - Got an event object 0x89d79f8
21:33:58.082698 [debug]  Client - Got an event object 0x87ffb00
21:33:58.082714 [debug]  Client - Got an event object 0x8977af8
21:33:58.082731 [debug]  Client - Got an event object 0x89253f8
21:33:58.082747 [debug]  Client - Got an event object 0x8847a48
21:33:58.082765 [debug]  Client - Done s1
21:33:58.082780 [debug]  Client - Done s2
21:33:58.082797 [debug]  Client - Done s3 419
21:33:58.082813 [debug]  Client - Got an event object 0x8847ce0
21:33:58.082830 [debug]  Client - Done s1
21:33:58.082846 [debug]  Client - Done s2
21:33:58.082862 [debug]  Client - Done s3 927
21:33:58.082878 [debug]  Client - Got an event object 0x8847fd8
21:33:58.082895 [debug]  Client - Done s1
21:33:58.082910 [debug]  Client - Done s2
21:33:58.082927 [debug]  Client - Done s3 1431
21:33:58.082942 [debug]  Client - Got an event object 0x88482c8
21:33:58.082959 [debug]  Client - Done s1
21:33:58.082974 [debug]  Client - Done s2
21:33:58.082989 [debug]  Client - Done s3 1465
21:33:58.083004 [debug]  Client - Got an event object 0x88483d8
21:33:58.083022 [debug]  Client - Done s1
21:33:58.083037 [debug]  Client - Done s2
21:33:58.083054 [debug]  Client - Done s3 1986
21:33:58.083070 [debug]  Client - Got an event object 0x88486d8
21:33:58.083087 [debug]  Client - Done s1
21:33:58.083102 [debug]  Client - Done s2
21:33:58.083119 [debug]  Client - Done s3 2507
21:33:58.083134 [debug]  Client - Got an event object 0x88489c0
21:33:58.083151 [debug]  Client - Done s1
21:33:58.083167 [debug]  Client - Done s2
21:33:58.083183 [debug]  Client - Done s3 2992
...