[mythtv-users] CoreContext programinfo.cpp:340 (ProgramInfo) - ProgramInfo(): Failed to find recorded entry for 0.
Stephen Worthington
stephen_agent at jsw.gen.nz
Sat Jul 21 09:36:30 UTC 2018
On Sat, 21 Jul 2018 06:47:41 +0200, you wrote:
>On 21-07-18 05:31, Udo van den Heuvel wrote:
>> On 20-07-18 09:34, Stephen Worthington wrote:
>>> Have you tried using Wireshark to see what is actually happening?
>>
>> I did capture some traffic using `tcpdump -v -i lo -n 'port 3306' -w
>> /Myth/capture.cap`.
>>
>> We have these three events:
>>
>> 2018-07-20 21:22:00 140284100806400 [Warning] Aborted connection 6986 to
>> db: 'mythconverg' user: 'mythtv' host: 'localhost' (Got timeout reading
>> communication packets)
>> 2018-07-20 22:25:38 140284352300800 [Warning] Aborted connection 7056 to
>> db: 'mythconverg' user: 'mythtv' host: 'localhost' (Got timeout reading
>> communication packets)
>> 2018-07-21 3:32:00 140284147431168 [Warning] Aborted connection 7516 to
>> db: 'mythconverg' user: 'mythtv' host: 'localhost' (Got timeout reading
>> communication packets)
>>
>> Now how to deduce what is happening from the capture?
>
>21:21:55.753365 IP (tos 0x8, ttl 64, id 53529, offset 0, flags [DF],
>proto TCP (6), length 40)
> localhost.localdomain.58646 > localhost.localdomain.mysql: Flags
>[R], cksum 0x4196 (correct), seq 3035810132, win 0, length 0
> 0x0000: 4508 0028 d119 4000 4006 6bac 7f00 0001 E..(.. at .@.k.....
> 0x0010: 7f00 0001 e516 0cea b4f2 c954 0000 0000 ...........T....
> 0x0020: 5004 0000 4196 0000 P...A...
>21:22:55.810346 IP (tos 0x0, ttl 64, id 64105, offset 0, flags [DF],
>proto TCP (6), length 60)
> localhost.localdomain.58650 > localhost.localdomain.mysql: Flags
>[S], cksum 0xfe30 (incorrect -> 0xb3f3), seq 3551475532, win 43690,
>options [mss 65495,sackOK,TS val 4143906107 ecr 0,nop,wscale 7], length 0
> 0x0000: 4500 003c fa69 4000 4006 4250 7f00 0001 E..<.i at .@.BP....
> 0x0010: 7f00 0001 e51a 0cea d3af 374c 0000 0000 ..........7L....
> 0x0020: a002 aaaa fe30 0000 0204 ffd7 0402 080a .....0..........
> 0x0030: f6fe fd3b 0000 0000 0103 0307 ...;........
>21:22:55.810364 IP (tos 0x0, ttl 64, id 0, offset 0, flags [DF], proto
>TCP (6), length 60)
> localhost.localdomain.mysql > localhost.localdomain.58650: Flags
>[S.], cksum 0xfe30 (incorrect -> 0xefae), seq 1087541030, ack
>3551475533, win 43690, options [mss 65495,sackOK,TS val 4143906107 ecr
>4143906107,nop,wscale 7], length 0
> 0x0000: 4500 003c 0000 4000 4006 3cba 7f00 0001 E..<.. at .@.<.....
> 0x0010: 7f00 0001 0cea e51a 40d2 8f26 d3af 374d ........ at ..&..7M
> 0x0020: a012 aaaa fe30 0000 0204 ffd7 0402 080a .....0..........
> 0x0030: f6fe fd3b f6fe fd3b 0103 0307 ...;...;....
>21:22:55.810376 IP (tos 0x0, ttl 64, id 64106, offset 0, flags [DF],
>proto TCP (6), length 52)
> localhost.localdomain.58650 > localhost.localdomain.mysql: Flags
>[.], cksum 0xfe28 (incorrect -> 0xc1f3), seq 1, ack 1, win 342, options
>[nop,nop,TS val 4143906107 ecr 4143906107], length 0
> 0x0000: 4500 0034 fa6a 4000 4006 4257 7f00 0001 E..4.j at .@.BW....
> 0x0010: 7f00 0001 e51a 0cea d3af 374d 40d2 8f27 ..........7M at ..'
> 0x0020: 8010 0156 fe28 0000 0101 080a f6fe fd3b ...V.(.........;
> 0x0030: f6fe fd3b ...;
>21:22:55.810405 IP (tos 0x0, ttl 64, id 64107, offset 0, flags [DF],
>proto TCP (6), length 52)
> localhost.localdomain.58650 > localhost.localdomain.mysql: Flags
>[F.], cksum 0xfe28 (incorrect -> 0xc1f2), seq 1, ack 1, win 342, options
>[nop,nop,TS val 4143906107 ecr 4143906107], length 0
> 0x0000: 4500 0034 fa6b 4000 4006 4256 7f00 0001 E..4.k at .@.BV....
> 0x0010: 7f00 0001 e51a 0cea d3af 374d 40d2 8f27 ..........7M at ..'
> 0x0020: 8011 0156 fe28 0000 0101 080a f6fe fd3b ...V.(.........;
> 0x0030: f6fe fd3b ...;
>21:22:55.810681 IP (tos 0x8, ttl 64, id 16898, offset 0, flags [DF],
>proto TCP (6), length 145)
> localhost.localdomain.mysql > localhost.localdomain.58650: Flags
>[P.], cksum 0xfe85 (incorrect -> 0xb1b0), seq 1:94, ack 2, win 342,
>options [nop,nop,TS val 4143906107 ecr 4143906107], length 93
> 0x0000: 4508 0091 4202 4000 4006 fa5a 7f00 0001 E...B. at .@..Z....
> 0x0010: 7f00 0001 0cea e51a 40d2 8f27 d3af 374e ........ at ..'..7N
> 0x0020: 8018 0156 fe85 0000 0101 080a f6fe fd3b ...V...........;
> 0x0030: f6fe fd3b 5900 0000 0a35 2e35 2e35 2d31 ...;Y....5.5.5-1
> 0x0040: 302e 322e 3136 2d4d 6172 6961 4442 002c 0.2.16-MariaDB.,
> 0x0050: 1e00 004e 5d69 7533 3c72 4c00 fef7 0802 ...N]iu3<rL.....
> 0x0060: 00bf 8115 0000 0000 0000 0700 0000 632e ..............c.
> 0x0070: 5852 7164 237c 6979 4735 006d 7973 716c XRqd#|iyG5.mysql
> 0x0080: 5f6e 6174 6976 655f 7061 7373 776f 7264 _native_password
> 0x0090: 00 .
>21:22:55.810728 IP (tos 0x8, ttl 64, id 62666, offset 0, flags [DF],
>proto TCP (6), length 40)
> localhost.localdomain.58650 > localhost.localdomain.mysql: Flags
>[R], cksum 0xb4db (correct), seq 3551475534, win 0, length 0
> 0x0000: 4508 0028 f4ca 4000 4006 47fb 7f00 0001 E..(.. at .@.G.....
> 0x0010: 7f00 0001 e51a 0cea d3af 374e 0000 0000 ..........7N....
> 0x0020: 5004 0000 b4db 0000 P.......
>21:23:55.971811 IP (tos 0x0, ttl 64, id 49286, offset 0, flags [DF],
>proto TCP (6), length 60)
> localhost.localdomain.58654 > localhost.localdomain.mysql: Flags
>[S], cksum 0xfe30 (incorrect -> 0xa9c9), seq 1656538979, win 43690,
>options [mss 65495,sackOK,TS val 4143966268 ecr 0,nop,wscale 7], length 0
> 0x0000: 4500 003c c086 4000 4006 7c33 7f00 0001 E..<.. at .@.|3....
> 0x0010: 7f00 0001 e51e 0cea 62bc c763 0000 0000 ........b..c....
> 0x0020: a002 aaaa fe30 0000 0204 ffd7 0402 080a .....0..........
> 0x0030: f6ff e83c 0000 0000 0103 0307 ...<........
>
>
>These are the packets or the first event plus one minute before and
>after. What do I look for?
>
>Kind regards,
>Udo
It is easier to analyse a packet capture if you load the file into
Wireshark. It has many more tools to analyse things, and will break
down most types of data such as MySQL protocol into understandable
fields. You can also tell it to display the absolute times on the
packets instead of the relative times, which really helps with
matching things between log files. Use View > Time Display Format.
When you do a capture, if you do a command like this at the same time:
root at mypvr:~# netstat -alpn | grep mythf | grep 3306
tcp 0 0 10.0.2.4:53992 10.0.2.4:3306 ESTABLISHED
3715/mythfrontend.r
tcp 0 0 10.0.2.4:54506 10.0.2.4:3306 ESTABLISHED
3715/mythfrontend.r
that will give you the port numbers that mythfrontend is using to
connect to MySQL, so that you can distinguish the frontend connections
from the backend ones.
In Wireshark, I find it useful to get it to filter out one
"conversation" - one TCP connection, for example. You do that with
Analyze > Conversation Filter, or alternatively get it to colorize a
conversation using View > Colorize Conversation.
In your packets above, I think you can discount the checksum errors -
I think that checksums are not used on the localhost interface. Apart
from that, without better decoding of the packets it is difficult to
see what is going on. There are parts of three "conversations" with
MySQL on port 3306 - from ports 58646, 58650 and 58654. But from what
is there I can not tell which is from mythfrontend and which from
mythbackend.
More information about the mythtv-users
mailing list