MediaPortal 2 development snapshot (2014-12-19) (3 Viewers)

[Wyatt]

Hi
I am glad you solved the problem.

But,is this blog relevant in your case?
http://richardsleegers.blogspot.se/2006/04/error-1305-error-reading-from-file-msi.html
It would be good to know for the future.
/mrj

Hi mrj,
You may be onto something here. When I changed the view in Windows Explorer I noticed my "Downloads" folder had a little padlock on it, which apparently means "No access to (general) Users"
Some good information can be found here: http://answers.microsoft.com/en-us/...ders-for/e4b100a7-f65d-49c6-8555-6df40d35676a.
I added myself to the "Permissions" list, then I went back to see if I could run the .msi again. It seemed to work on this occasion because I got to the screen that allows me to Change/Repair/Uninstall MP2.
Cheers
Wyatt.

 

[mrj]

I know my away around github, but can't get my head around what bits come from where in the MP1 and MP2 repositories for building TVE3.5.

Only @morpheus_xx knows for sure but you can start here
https://forum.team-mediaportal.com/...dated-for-10th-ae-update-1-2014-09-13.114068/
and
https://forum.team-mediaportal.com/...date-1-2014-09-13.114068/page-55#post-1096512
and
https://forum.team-mediaportal.com/...date-1-2014-09-13.114068/page-54#post-1095778

/mrj

 

[rat6666]

I know my away around github, but can't get my head around what bits come from where in the MP1 and MP2 repositories for building TVE3.5.

Only @morpheus_xx knows for sure but you can start here
/mrj

Cheers mrj, I will give it a crack, but i'm starting from zero knowledge of the source. I suppose I can submit it as a bug somewhere? I'm also not sure if it something specific to my PC.

 

[mrj]

I suppose I can submit it as a bug somewhere?

bug-reports
https://forum.team-mediaportal.com/forums/submit-bug-reports.532/
code-patches
https://forum.team-mediaportal.com/forums/submit-code-patches.533/
/mrj

 

[mrj]

rat6666 said:
↑
I know my away around github, but can't get my head around what bits come from where in the MP1 and MP2 repositories for building TVE3.5.
Click to expand...
Only @morpheus_xx knows for sure but you can start here
https://forum.team-mediaportal.com/t...dated-for-10th-ae-update-1-2014-09-13.114068/
and
https://forum.team-mediaportal.com/t...date-1-2014-09-13.114068/page-55#post-1096512
and
https://forum.team-mediaportal.com/t...date-1-2014-09-13.114068/page-54#post-1095778
/mrj

Hi rat6666
The best repository is of course the weekly branch
https://github.com/MediaPortal/MediaPortal-2/tree/Weekly

TV-Server
https://github.com/MediaPortal/MediaPortal-2/tree/Weekly/MediaPortal/Incubator/SlimTvService

TV-Client
https://github.com/MediaPortal/MediaPortal-2/tree/Weekly/MediaPortal/Incubator/SlimTvClient

/mrj

 

[osre]

I have just cloned the weekly repo, loaded the client solution, build, run.
Works like a charm.
It was easy to track down the "Error invoking timeshift action" error.
As expected, the error is forwarded from the server.
It took me about 10 min. from opening an MP2 solution for the 1st time to step through the code and locate the source of an nasty problem.
The code readability is really good, Congratulations to the MP2 team
Now I need to setup a remote debugger session to my server (have no tuner in my dev computer).
Lets see what I can find out.

 

[osre]

I finally got debugging to a remote windows service running
I narrowed down the "Error invoking timeshift action" error.
Even that "AdvancedCardAllocation.GetAvailableCardsForChannel" claims to have found a free card, "control.StartTimeShifting" (SlimTvService.cs:650) gets as result "AllCardsBusy".
According to the log, the card is tuned to a different transponder.
I've added a single repeatition to the "control.StartTimeShifting" call, but that does not work either. Even when I move the current line in debugger back several times, after more or less long delays, the error stays the same. So may be the stop of the previous channel was not successful?
For some reason I can not step into the "control.StartTimeShifting" call.
I'll play around some more, may be investigating what happens before that.
Attached you can find my server log, with several successful channel changes, and the failed one at the end of the log.

 

[mm1352000]

@osre
Here's the issue...
Final successful tune request:
[collapse]
[2014-12-26 11:01:49,141] [212656 ] [51 ] [DEBUG] - Starting timeshifiting with username fe80::81e1:6c1c:e852:3bf9%2-0 on channel id 1281
[2014-12-26 11:01:49,298] [212812 ] [51 ] [DEBUG] - Controller: StartTimeShifting ProSieben 1281
[2014-12-26 11:01:49,298] [212812 ] [51 ] [DEBUG] - Controller: epg stop
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: find free card for channel ProSieben
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: find card for channel ProSieben
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: got 1 tuning details for ProSieben
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: channel #1 DVBS:TVProSiebenSat.1 ProSieben Freq:12545000 ONID:1 TSID:1107 SID:17501 PMT:97 FTA:True LCN:7 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DiSEqC:None band:Universal Pilot:NotSet RollOff:NotSet
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:1 typevbS can tune to channel
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:2 typevbS is disabled
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:3 typevbIP is disabled
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:4 type:Analog is disabled
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: found 1 card(s) for channel
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 5 msec
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: found 1 free card(s)
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 7 msec
[2014-12-26 11:01:49,298] [212812 ] [51 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=16, tuningdetails=DVBS:TVProSiebenSat.1 ProSieben Freq:12545000 ONID:1 TSID:1107 SID:17501 PMT:97 FTA:True LCN:7 SymbolRate:22000 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate5_6 DiSEqC:None band:Universal Pilot:NotSet RollOff:NotSet
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:1 typevbS users: 0
[2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:1 typevbS is available
[2014-12-26 11:01:49,298] [212812 ] [51 ] [DEBUG] - Controller: try max 1 of 1 cards for timeshifting[/collapse]

Final failing tune request:
[collapse]
[2014-12-26 11:02:16,063] [239578 ] [29 ] [DEBUG] - Starting timeshifiting with username 2a02:6d40:100:2e31:81e1:6c1c:e852:3bf9-0 on channel id 1139
[2014-12-26 11:02:16,095] [239609 ] [29 ] [DEBUG] - Controller: StartTimeShifting VOX 1139
[2014-12-26 11:02:16,095] [239609 ] [29 ] [DEBUG] - Controller: epg stop
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: find free card for channel VOX
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: find card for channel VOX
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: got 1 tuning details for VOX
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: channel #1 DVBS:TVRTL World VOX Freq:12188000 ONID:1 TSID:1089 SID:12060 PMT:47 FTA:True LCN:8 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DiSEqC:None band:Universal Pilot:NotSet RollOff:NotSet
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: card:1 typevbS can tune to channel
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: card:2 typevbS is disabled
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: card:3 typevbIP is disabled
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: card:4 type:Analog is disabled
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: found 1 card(s) for channel
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - AdvancedCardAllocation.GetAvailableCardsForChannel took 3 msec
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: found 1 free card(s)
[2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - AdvancedCardAllocation.GetFreeCardsForChannel took 5 msec
[2014-12-26 11:02:16,126] [239641 ] [29 ] [DEBUG] - CardReservationBase.RequestCardTuneReservation: placed reservation with id=17, tuningdetails=DVBS:TVRTL World VOX Freq:12188000 ONID:1 TSID:1089 SID:12060 PMT:47 FTA:True LCN:8 SymbolRate:27500 Modulation:ModNotSet Polarisation:LinearH InnerFecRate:Rate3_4 DiSEqC:None band:Universal Pilot:NotSet RollOff:NotSet
[2014-12-26 11:02:16,126] [239641 ] [29 ] [INFO ] - Controller: card:1 typevbS users: 1
[2014-12-26 11:02:16,126] [239641 ] [29 ] [INFO ] - Controller: card:1 typevbS is not available, tuned to different transponder[/collapse]

Note the first line in each of those sections:
[2014-12-26 11:01:49,141] [212656 ] [51 ] [DEBUG] - Starting timeshifiting with username fe80::81e1:6c1c:e852:3bf9%2-0 on channel id 1281
[2014-12-26 11:02:16,063] [239578 ] [29 ] [DEBUG] - Starting timeshifiting with username 2a02:6d40:100:2e31:81e1:6c1c:e852:3bf9-0 on channel id 1139

Those IPv6 addresses are different, which will cause TVE to think that they are requests from 2 different "users" (PCs). User 2a02:6d... cannot use one enabled/capable tuner because user fe80::81... is still using it to receive ProSieben.

Did those tune requests really come from a single PC?
If so, it seems like the IP address changed or something...

 

[mm1352000]

Ahhhh, look a few lines up in the log...
[2014-12-26 11:02:08,407] [231922 ] [51 ] [DEBUG] - UPnPServer: Removing obsolete IP endpoint IP 'fe80::148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,407] [231922 ] [51 ] [INFO ] - UPnPServerController: SSDP disabled for IP endpoint 'fe80::148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,407] [231922 ] [51 ] [DEBUG] - UPnPServer: Removing obsolete IP endpoint IP '2001:0:5ef5:79fd:148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,407] [231922 ] [51 ] [INFO ] - UPnPServerController: SSDP disabled for IP endpoint '2001:0:5ef5:79fd:148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,454] [231969 ] [30 ] [INFO ] - SSDPServerController: Stopping listening for multicast messages at IP endpoint 'fe80::148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,470] [231984 ] [43 ] [INFO ] - SSDPServerController: Stopping listening for multicast messages at IP endpoint 'fe80::148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,470] [231984 ] [51 ] [INFO ] - SSDPServerController: Stopping listening for multicast messages at IP endpoint '2001:0:5ef5:79fd:148e:1ac8:3f57:4de9'
[2014-12-26 11:02:08,470] [231984 ] [47 ] [INFO ] - SSDPServerController: Stopping listening for multicast messages at IP endpoint '2001:0:5ef5:79fd:148e:1ac8:3f57:4de9'

So, the IP endpoint that was previously used to identify the "user"/PC was removed. What could explain that? Maybe you disconnected a VPN or something???

 

[osre]

Yes, it's only a single client. It's even a singe session of the MP2 client (no restart)

This is the output from "ipconfig /all"

Ethernet-Adapter Ethernet:

   Verbindungsspezifisches DNS-Suffix: fritz.box
   Beschreibung. . . . . . . . . . . : Intel(R) Ethernet-Verbindung I217-V
   Physische Adresse . . . . . . . . : 74-D4-35-AA-D2-1B
   DHCP aktiviert. . . . . . . . . . : Ja
   Autokonfiguration aktiviert . . . : Ja
   IPv6-Adresse. . . . . . . . . . . : 2a02:6d40:100:2e31:81e1:6c1c:e852:3bf9(Bevorzugt)
   Temporäre IPv6-Adresse. . . . . . : 2a02:6d40:100:2e31:3de9:a5d0:e05e:ec95(Bevorzugt)
   IPv6-Adresse. . . . . . . . . . . : 2a02:6d40:161:4db7:81e1:6c1c:e852:3bf9(Verworfen)
   Verbindungslokale IPv6-Adresse  . : fe80::81e1:6c1c:e852:3bf9%5(Bevorzugt)
   IPv4-Adresse  . . . . . . . . . . : 192.168.178.34(Bevorzugt)
   Subnetzmaske  . . . . . . . . . . : 255.255.255.0
   Lease erhalten. . . . . . . . . . : Donnerstag, 11. Dezember 2014 18:37:21
   Lease läuft ab. . . . . . . . . . : Montag, 5. Januar 2015 09:19:11
   Standardgateway . . . . . . . . . : fe80::a96:d7ff:fe6f:7cac%5
                                       192.168.178.1
   DHCP-Server . . . . . . . . . . . : 192.168.178.1
   DHCPv6-IAID . . . . . . . . . . . : 57988149
   DHCPv6-Client-DUID. . . . . . . . : 00-01-00-01-1B-4E-5D-AD-74-D4-35-AA-D2-1B

   DNS-Server  . . . . . . . . . . . : fd00::a96:d7ff:fe6f:7cac
                                       192.168.178.1
   NetBIOS über TCP/IP . . . . . . . : Aktiviert

"fe80::81e1:6c1c:e852:3bf9" is my connection local address (with an "%5" at the end instead of the "%2" as in the log
"2a02:6d40:100:2e31:81e1:6c1c:e852:3bf9" is the preferred address

Looks like as if sometimes the local address is used, and sometimes the other one[DOUBLEPOST=1419593716][/DOUBLEPOST]About your 2nd post: I'm connected to the server via remote desktop, but the connection was open all the time.
Additionally the Visual Studio remote debugger is connected to the server.
For some reason the problem does not occur for some time now.