[further infos missing] MP2.2 client crashes on open (after upgrade) (1 Viewer)

tlhackque

Portal Member
June 3, 2015
42
1
Home Country
United States of America United States of America
MediaPortal 2 Version: 2.2

Description
Was successfully running MP2.1.1, upgraded to 2.2. Combined client/server under W10 pro.

Upgrade appeared to be successful.

However, opening client first time showed splash screen - then nothing.

Task manager showed server doing considerable IO. Waited for it to finish.

Attempting to launch client gets no visble response. Task manager shows client appearing momentarily, then exiting.

Restarted MP2-Server service - initializes with much less IO. But still client doesn't open.

Attached logs include crash.log, showing an "Access is denied" for some HTTPListener.

I run Norton Internet Security - its history does NOT show it interfereing.

FWIW, the host has both IPv4 and IPv6 active. It has dual monitors.

Thanks in advance.

Steps to Reproduce:
Upgrade 2.1.1 to 2.2.
Open Client.
Task Manager will show client process appear, then disappear.
Nothing on screen - as detailed above.
 

Attachments

  • MediaPortal2-Logs-2018-11-12-06.45.50.zip
    11.1 MB

Lehmden

Retired Team Member
  • Premium Supporter
  • December 17, 2010
    12,553
    3,934
    Lehmden
    Home Country
    Germany Germany
    Hi.

    Without reading your logs...

    2.2 and 2.1.x don't have compatible databases. During first time installation of MP 2.2 an existing 2.1.x database will be upgraded to be 2.2 compatible. But this only was developed and tested for upgrading from 2.1.3, not 2.1.1. I'm not sure if there are differences between 2.1.1 and 2.1.3 related to the database. 2.1.1 is so long ago (more than a year I think), I simply can not remember any more. But I'm 100% sure the upgrade process only was developed and tested for a 2.1.3 installation...

    Aside this, IPv6 is not really working stable with older infrastructure (or software). You best disable IPv6 completely if you don't need it elsewhere. At least disable it for MP2 in settings...

    After reading your logs...

    The first server start (the one with the huge amount of I/O) has at least tried to upgrade the database. This lasts a while and is the reason for the massive I/O. This is necessary and can not be avoided, if you don't want to start from scratch. You best don't touch the system while the upgrade is processed.

    In your logs there are a lot of HTTP 403 errors (403 means access forbidden) so most likely your security settings are not as needed by MP...

    Your client has crashed some times due to issues with ThreadPool.Init(). This should not happen and looks like something is blocking the client from being executed. But this has to be investigated by a dev, I fear...
     

    HTPCSourcer

    Retired Team Member
  • Premium Supporter
  • May 16, 2008
    11,418
    2,335
    Home Country
    Germany Germany
    Hello,

    Your sever logs show no problems: server.1.log is the first log of the 2.2 setup, displaying the successful read-in of your media items. server.log is your second attempt after you killed everything. Again it displays no issues at all - until you killed the processes once again.

    So any issue is on the client and/or the communication side. When you downloaded the software, did you then tick the "Allow access" in the file properties of the setup.exe? If no, tick it and run the installer again (Repair). If yes, do it anyway.
     

    tlhackque

    Portal Member
    June 3, 2015
    42
    1
    Home Country
    United States of America United States of America
    Additional data that might be relevant:

    Netstat shows MP2-Server.exe listening on and in TIME_WAIT for ports in a private subnet.

    192.168.56.0/24 is not routed in this network, however it is associated with a VirtualBox host-only network.
    VirtualBox is not running, but the adapter is always present. (It is used to isolate VMs; they can communicate
    with each-other & the host, but not the public network.)

    192.168.148.0/24 is the public subnet (it is a bridge between an ethernet interface and a TAP). MP2 is also listening on it.

    None of this has changed; MP2.1.1 worked. But perhaps MP2.2 is confused by the "extra" interface?

    If the client and server are using different interfaces, it might explain a connection issue... but not why this should start happening with the update.

    Here is netstat extract ("Scream" -- 192.168.148.110 -- is the MP2 host):

    TCP 192.168.56.1:31456 Scream:58955 ESTABLISHED
    [MP2-Server.exe]
    TCP 192.168.56.1:31456 Scream:58956 ESTABLISHED
    [MP2-Server.exe]
    TCP 192.168.56.1:58577 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58578 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58680 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58681 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58781 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58782 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58879 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58880 Scream:31456 TIME_WAIT
    TCP 192.168.56.1:58955 Scream:31456 ESTABLISHED
    [MP2-Server.exe]
    TCP 192.168.56.1:58956 Scream:31456 ESTABLISHED
    [MP2-Server.exe]
    TCP 192.168.148.110:554 Scream:0 LISTENING
    [MP2-Server.exe]


    ...

    ===========================================================================
    Interface List
    10...00 ff 7a 3d c4 c2 ......Microsoft Network Adapter Multiplexor Driver
    18...0a 00 27 00 00 12 ......VirtualBox Host-Only Ethernet Adapter #2
    1...........................Software Loopback Interface 1
    ===========================================================================

    IPv4 Route Table
    ===========================================================================
    Active Routes:
    Network Destination Netmask Gateway Interface Metric
    0.0.0.0 0.0.0.0 192.168.148.1 192.168.148.110 281
    127.0.0.0 255.0.0.0 On-link 127.0.0.1 331
    127.0.0.1 255.255.255.255 On-link 127.0.0.1 331
    127.255.255.255 255.255.255.255 On-link 127.0.0.1 331
    192.168.56.0 255.255.255.0 On-link 192.168.56.1 281
    192.168.56.1 255.255.255.255 On-link 192.168.56.1 281
    192.168.56.255 255.255.255.255 On-link 192.168.56.1 281
    192.168.148.0 255.255.255.0 On-link 192.168.148.110 281
    192.168.148.110 255.255.255.255 On-link 192.168.148.110 281
    192.168.148.255 255.255.255.255 On-link 192.168.148.110 281
    224.0.0.0 240.0.0.0 On-link 127.0.0.1 331
    224.0.0.0 240.0.0.0 On-link 192.168.56.1 281
    224.0.0.0 240.0.0.0 On-link 192.168.148.110 281
    255.255.255.255 255.255.255.255 On-link 127.0.0.1 331
    255.255.255.255 255.255.255.255 On-link 192.168.56.1 281
    255.255.255.255 255.255.255.255 On-link 192.168.148.110 281
    ===========================================================================
    Persistent Routes:
    Network Address Netmask Gateway Address Metric
    0.0.0.0 0.0.0.0 192.168.148.1 Default
    ===========================================================================

    IPv6 Route Table
    ===========================================================================
    (etc)

    arp:
    Interface: 192.168.148.110 --- 0xa
    Internet Address Physical Address Type
    192.168.148.1 70-ca-9b-77-c1-af dynamic
    ... etc
    Interface: 192.168.56.1 --- 0x12
    Internet Address Physical Address Type
    192.168.56.255 ff-ff-ff-ff-ff-ff static
    224.0.0.22 01-00-5e-00-00-16 static
    224.0.0.251 01-00-5e-00-00-fb static
    224.0.0.252 01-00-5e-00-00-fc static
    224.0.1.60 01-00-5e-00-01-3c static
    230.114.31.161 01-00-5e-72-1f-a1 static
    239.192.152.143 01-00-5e-40-98-8f static
    239.255.255.250 01-00-5e-7f-ff-fa static
    255.255.255.255 ff-ff-ff-ff-ff-ff static

     

    tlhackque

    Portal Member
    June 3, 2015
    42
    1
    Home Country
    United States of America United States of America
    So any issue is on the client and/or the communication side. When you downloaded the software, did you then tick the "Allow access" in the file properties of the setup.exe? If no, tick it and run the installer again (Repair). If yes, do it anyway.

    It is the client crash.log that shows the traceback, so I agree that it's a client or comm issue.
    = Exception Information
    == Exception: System.Reflection.TargetInvocationException: Exception has been thrown by the target of an invocation. ---> System.Net.HttpListenerException: Access is denied
    at System.Net.HttpListener.AddAllPrefixes()
    at System.Net.HttpListener.Start()
    (see the log in the original posting for the rest)
    There is no "Allow access" in the properties of setup.exe.

    I only restarted the MP2-server service once (manually). I never killed the client process. It dies by itself...

    As far as "disable IPv6 for MP2 in settings":
    1) I can't open the client, so settings aren't accessible.
    2) Thus, no settings change since the previous release, which had no issues..


    I tried to run setup again (as "repair"), and got:"one or more issues caused the setup to fail. Please fix the issues and then retry setup." "0x80070643 - Fatal error during installation":.

    It pointed to a log file "for more information" - but I don't see anything interesting. That file does point to an HTML log, which is attached. (renamed to .txt so I could upload it). The HTML seems to indicate some unhandled conditions...

    Here is the text log:
    [3CE4:3018][2018-11-12T10:07:20]i001: Burn v3.11.0.1701, Windows v10.0 (Build 17134: Service Pack 0), path: C:\Users\timothe\AppData\Local\Temp\{04873559-B5DF-4843-B616-49BEEF22AED5}\.cr\MP2-Setup.exe
    [3CE4:3018][2018-11-12T10:07:20]i009: Command Line: '-burn.clean.room=C:\Temp\mp\MP2-Setup.exe -burn.filehandle.attached=608 -burn.filehandle.self=604'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting string variable 'WixBundleOriginalSource' to value 'C:\Temp\mp\MP2-Setup.exe'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting string variable 'WixBundleOriginalSourceFolder' to value 'C:\Temp\mp\'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting string variable 'WixBundleLog' to value 'C:\Users\timothe\AppData\Local\Temp\MediaPortal_2_20181112100720.log'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting string variable 'WixBundleManufacturer' to value 'Team MediaPortal'
    [3CE4:35C8][2018-11-12T10:07:20]i000: Setting numeric variable 'WixStdBALanguageId' to value 1033
    [3CE4:35C8][2018-11-12T10:07:20]i000: Setting version variable 'WixBundleFileVersion' to value '2.2.1811.11777'
    [3CE4:3018][2018-11-12T10:07:20]i100: Detect begin, 8 packages
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting version variable 'D3DX9_41_Version' to value '9.26.952.2844'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting version variable 'D3DX9_43_Version' to value '9.29.952.3111'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting version variable 'Dokan_1000_Version' to value '1.0.1.0'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting version variable 'MFC100_Version_x86' to value '10.0.40219.332'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting version variable 'MFC120_Version_x86' to value '12.0.21005.1'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting version variable 'MFC140_Version_x86' to value '14.14.26405.0'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting string variable 'MFC90_Version_x86' to value '9.0.30729'
    [3CE4:3018][2018-11-12T10:07:20]i000: Setting string variable 'NETFRAMEWORK45' to value '461808'
    [3CE4:3018][2018-11-12T10:07:20]i052: Condition 'NETFRAMEWORK45 >= 461308' evaluates to true.
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: NetFx471Web, state: Present, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: VC2008SP1_x86, state: Absent, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: VC2010_x86, state: Absent, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: VC2013_x86, state: Absent, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: VC2017_x86, state: Absent, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: directx9, state: Absent, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: dokan, state: Absent, cached: None
    [3CE4:3018][2018-11-12T10:07:20]i101: Detected package: MainPackage, state: Present, cached: Complete
    [3CE4:3018][2018-11-12T10:07:20]i199: Detect complete, result: 0x0
    [3CE4:3018][2018-11-12T10:07:23]i200: Plan begin, 8 packages, action: Repair
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: NetFx471Web
    [3CE4:3018][2018-11-12T10:07:23]i000: Setting string variable 'NetFx471FullLog' to value 'C:\Users\timothe\AppData\Local\Temp\MediaPortal_2_20181112100720_000_NetFx471Web.log'
    [3CE4:3018][2018-11-12T10:07:23]i052: Condition 'NOT MFC90_Version_x86' evaluates to false.
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: VC2008SP1_x86
    [3CE4:3018][2018-11-12T10:07:23]i052: Condition '(NOT MFC100_Version_x86 >= v10.0.30319.1) OR NOT MFC100_Version_x86' evaluates to false.
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: VC2010_x86
    [3CE4:3018][2018-11-12T10:07:23]i052: Condition '(NOT MFC120_Version_x86 >= v12.0.21005.1) OR NOT MFC120_Version_x86' evaluates to false.
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: VC2013_x86
    [3CE4:3018][2018-11-12T10:07:23]i052: Condition '(NOT MFC140_Version_x86 >= v14.11.25325) OR NOT MFC140_Version_x86' evaluates to false.
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: VC2017_x86
    [3CE4:3018][2018-11-12T10:07:23]i052: Condition '(NOT D3DX9_41_Version >= v9.26.952.2844) OR NOT D3DX9_41_Version OR (NOT D3DX9_43_Version >= v9.29.952.3111) OR NOT D3DX9_43_Version' evaluates to false.
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: directx9
    [3CE4:3018][2018-11-12T10:07:23]i052: Condition '(NOT Dokan_1000_Version >= v1.0.0.0) OR NOT Dokan_1000_Version' evaluates to false.
    [3CE4:3018][2018-11-12T10:07:23]w321: Skipping dependency registration on package with no dependency providers: dokan
    [3CE4:3018][2018-11-12T10:07:23]i000: Setting string variable 'WixBundleLog_MainPackage' to value 'C:\Users\timothe\AppData\Local\Temp\MediaPortal_2_20181112100720_001_MainPackage.log'
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: NetFx471Web, state: Present, default requested: Repair, ba requested: Repair, execute: Repair, rollback: None, cache: Yes, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: VC2008SP1_x86, state: Absent, default requested: Absent, ba requested: Absent, execute: None, rollback: None, cache: No, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: VC2010_x86, state: Absent, default requested: Absent, ba requested: Absent, execute: None, rollback: None, cache: No, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: VC2013_x86, state: Absent, default requested: Absent, ba requested: Absent, execute: None, rollback: None, cache: No, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: VC2017_x86, state: Absent, default requested: Absent, ba requested: Absent, execute: None, rollback: None, cache: No, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: directx9, state: Absent, default requested: Absent, ba requested: Absent, execute: None, rollback: None, cache: No, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: dokan, state: Absent, default requested: Absent, ba requested: Absent, execute: None, rollback: None, cache: No, uncache: No, dependency: None
    [3CE4:3018][2018-11-12T10:07:23]i201: Planned package: MainPackage, state: Present, default requested: Repair, ba requested: Repair, execute: Repair, rollback: None, cache: No, uncache: No, dependency: Register
    [3CE4:3018][2018-11-12T10:07:23]i299: Plan complete, result: 0x0
    [3CE4:3018][2018-11-12T10:07:23]i300: Apply begin
    [3CE4:3018][2018-11-12T10:07:23]i010: Launching elevated engine process.
    [3CE4:3018][2018-11-12T10:07:25]i011: Launched elevated engine process.
    [3CE4:3018][2018-11-12T10:07:25]i012: Connected to elevated engine.
    [4464:626C][2018-11-12T10:07:25]i358: Pausing automatic updates.
    [4464:626C][2018-11-12T10:07:25]i359: Paused automatic updates.
    [4464:626C][2018-11-12T10:07:25]i360: Creating a system restore point.
    [4464:626C][2018-11-12T10:07:25]i361: Created a system restore point.
    [4464:626C][2018-11-12T10:07:25]i371: Updating session, registration key: SOFTWARE\Microsoft\Windows\CurrentVersion\Uninstall\{aa2eb04a-9cc5-4263-8812-352f24614555}, resume: Active, restart initiated: No, disable resume: No
    [3CE4:69E8][2018-11-12T10:07:25]w343: Prompt for source of package: NetFx471Web, payload: NetFx471Web, path: C:\Temp\mp\redist\NDP471-KB4033344-Web.exe
    [3CE4:69E8][2018-11-12T10:07:25]i338: Acquiring package: NetFx471Web, payload: NetFx471Web, download from: http://go.microsoft.com/fwlink/?LinkId=852092
    [4464:3280][2018-11-12T10:08:11]i305: Verified acquired payload: NetFx471Web at path: C:\ProgramData\Package Cache\.unverified\NetFx471Web, moving to: C:\ProgramData\Package Cache\C0919415622D86C3D6AB19F0F92EA938788DB847\redist\NDP471-KB4033344-Web.exe.
    [4464:3280][2018-11-12T10:08:12]i304: Verified existing payload: MainPackage at path: C:\ProgramData\Package Cache\{7F9EFE02-2BCB-4431-A322-0B044F359709}v2.2.1811.11777\MP2-Setup.msi.
    [4464:626C][2018-11-12T10:08:12]i301: Applying execute package: NetFx471Web, action: Repair, path: C:\ProgramData\Package Cache\C0919415622D86C3D6AB19F0F92EA938788DB847\redist\NDP471-KB4033344-Web.exe, arguments: '"C:\ProgramData\Package Cache\C0919415622D86C3D6AB19F0F92EA938788DB847\redist\NDP471-KB4033344-Web.exe" /q /norestart /repair /ChainingPackage "MediaPortal 2" /log "C:\Users\timothe\AppData\Local\Temp\MediaPortal_2_20181112100720_000_NetFx471Web.log.html"'
    [4464:626C][2018-11-12T10:08:36]e000: Error 0x80070643: Process returned error: 0x80070643
    [4464:626C][2018-11-12T10:08:36]e000: Error 0x80070643: Failed to execute EXE package.
    [3CE4:3018][2018-11-12T10:08:36]e000: Error 0x80070643: Failed to configure per-machine EXE package.
    [3CE4:3018][2018-11-12T10:08:36]i319: Applied execute package: NetFx471Web, result: 0x80070643, restart: None
    [3CE4:3018][2018-11-12T10:08:36]e000: Error 0x80070643: Failed to execute EXE package.
    [4464:626C][2018-11-12T10:08:36]i351: Removing cached package: NetFx471Web, from path: C:\ProgramData\Package Cache\C0919415622D86C3D6AB19F0F92EA938788DB847\
    [4464:626C][2018-11-12T10:08:36]i372: Session end, registration key: SOFTWARE\Microsoft\Windows\CurrentVersion\Uninstall\{aa2eb04a-9cc5-4263-8812-352f24614555}, resume: ARP, restart: None, disable resume: No
    [4464:626C][2018-11-12T10:08:36]i371: Updating session, registration key: SOFTWARE\Microsoft\Windows\CurrentVersion\Uninstall\{aa2eb04a-9cc5-4263-8812-352f24614555}, resume: ARP, restart initiated: No, disable resume: No
    [3CE4:3018][2018-11-12T10:08:37]i399: Apply complete, result: 0x80070643, restart: None, ba requested restart: No
     

    Attachments

    • MediaPortal_2_20181112100720_000_NetFx471Web.log.html.txt
      735.3 KB

    HTPCSourcer

    Retired Team Member
  • Premium Supporter
  • May 16, 2008
    11,418
    2,335
    Home Country
    Germany Germany
    That may explain the .Net 4.0 error.

    Try to download the Net 4.7.1 package (see Wiki / Installation requirements for the link) and install it manually.
     

    tlhackque

    Portal Member
    June 3, 2015
    42
    1
    Home Country
    United States of America United States of America
    The Wiki says:
    The MediaPortal installer will automatically identify, download and install missing system components:
    The list includes .net.

    In any case, .net 4.7.2 is already installed. The repair error seems to be an issue with dual languages installed on this machine. (Which again, hasn't changed since the previous MP2 install.)

    The "Unhandled condition" in the re-install has to do with a "repair" attempted for "Microsoft .NET Framework 4.6.1 (FRA)".
    The error message in the log translates to:

    "Microsoft .NET Framework 4.5is already installed on this operating system, but is actually deactivated. To activate .NET Framework 4.5, use the "activate or deactivate" option on the windows control panel".​

    This should not cause the repair to fail. The active language on this machine is US English; French is also installed but seems to be confusing the MP2 "repair".

    However, this is probably a separate issue from the client fault...
     

    ge2301

    Lead Design MP2
  • Team MediaPortal
  • January 11, 2014
    8,705
    3,491
    Stuttgart
    Home Country
    Germany Germany
    In any case, .net 4.7.2 is already installed.
    Please install Net 4.7.1 manually, you need exactly this version as stated above.
    Why MP2.2 installer could not install it is another problem we need to investigate. It seems some users have similar issues.
     

    tlhackque

    Portal Member
    June 3, 2015
    42
    1
    Home Country
    United States of America United States of America
    Please install Net 4.7.1 manually, you need exactly this version as stated above.

    I did. When I tried to install 4.7.1 manually (from the given link), the installer exits with "4.7.1 or a higher version is already installed".
    That version is 4.7.2. In theory, the only difference should be bug fixes & you shouldn't depend on the patchlevel. (Of course, Microsoft does make mistakes.)

    Settings->APPs agrees that 4.7.2 is installed. (Both languages)

    Why the error message in the installer log refers to 4.5 & 4.6 is a mystery (those versions both are also installed). Perhaps the setup script's dependencies are inconsistent.
     

    tlhackque

    Portal Member
    June 3, 2015
    42
    1
    Home Country
    United States of America United States of America
    To be clear: it is not possible to install .NET 4.7.1 when 4.7.2 is installed. However, 4.7.2 should be compatible.

    I still can't run the client.

    I assume that I can't revert to 2.1.1 because of the database upgrade.

    Let me know if I can provide further information or if there's something else to try,

    Many thanks for your help with this.
     

    Users who are viewing this thread

    Top Bottom