MediaPortal 2 development snapshot (2014-12-19) (1 Viewer)

Wyatt

Portal Member
December 22, 2014
7
3
Home Country
Australia Australia
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

Portal Pro
January 27, 2012
252
100

rat6666

New Member
December 22, 2014
2
0
40
Home Country
United Kingdom United Kingdom
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

Portal Pro
January 27, 2012
252
100

osre

Retired Team Member
  • Premium Supporter
  • December 14, 2014
    775
    387
    Home Country
    Germany Germany
    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

    Retired Team Member
  • Premium Supporter
  • December 14, 2014
    775
    387
    Home Country
    Germany Germany
    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

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    @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 type:DvbS can tune to channel
    [2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:2 type:DvbS is disabled
    [2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:3 type:DvbIP 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 type:DvbS users: 0
    [2014-12-26 11:01:49,298] [212812 ] [51 ] [INFO ] - Controller: card:1 type:DvbS 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 type:DvbS can tune to channel
    [2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: card:2 type:DvbS is disabled
    [2014-12-26 11:02:16,095] [239609 ] [29 ] [INFO ] - Controller: card:3 type:DvbIP 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 type:DvbS users: 1
    [2014-12-26 11:02:16,126] [239641 ] [29 ] [INFO ] - Controller: card:1 type:DvbS 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

    Retired Team Member
  • Premium Supporter
  • September 1, 2008
    21,577
    8,224
    Home Country
    New Zealand New Zealand
    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

    Retired Team Member
  • Premium Supporter
  • December 14, 2014
    775
    387
    Home Country
    Germany Germany
    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"
    Code:
    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.
     

    Users who are viewing this thread

    Top Bottom