2012/01/08

WHS2011 再起動後にLaunchPadのサインインが失敗する

以前Windows Home Server2011コネクタのインストールが失敗することがありましたが、Lenovo ThinkPad T420のクリーンインストールを行った後は正常にインストールすることができました。

当然といえば当然ですが・・・

今度はパソコンを再起動するとLaunchPadのサインインに失敗する現象が発生しました。

調査等を含めた内容をメモしておきます。

なお、現時点で本内容は解決していません


※2012.01.08 追加・修正

本件は本日解決しました。

解決した内容はWHS2011 LaunchPadのサインインが失敗する原因はサービスだったの記事にしております。

現象:再起動するとLaunchPadのサインインができない

クライアントはWindows 7 64bitです。

WHS2011コネクタのインストール直後は下記の通り、問題ありません。

  • LaunchPadのサインインは問題ない
  • DashBoardのサインインは問題ない
  • LaunchPad経由でのクライアントバックアップは問題なく行える

クライアントを再起動すると下記の通り、問題が発生。

  • LaunchPadのサインインができなず、オフラインのメッセージがでる。
    image
      image
  • DashBoardのサインインは問題ない

セキュリティ、ネットワークを調査したがダメ

サーバ・クライアント両方のウィルス対策ソフトを停止、ファイアウォールも停止しましたが、結果は変わらず。

また、WHS2011コネクタを再インストールするも、インストール直後はいいけど、再起動すればダメ。

LANアナライザを使ってパケットを調査すると、サーバ宛のTCP6602に接続している感じがしない状態。

LANアナライザでIPv4、IPv6のIPアドレスで検索しても出てこないので・・・

LaunchPadに関わるファイルの場所

WHS2011コネクタをインストールするとWindows Server関係のファイルができます。

その中で各種設定ファイルやログがありますので、ファイルの場所を事前に調べておきました。

★クライアント設定ファイルの場所

  • LaunchPad
    C:\Users\ユーザ名\AppData\Local\Microsoft_Corporation\Launchpad.exe_xxxxx\6.1.0.0\user.config
  • DashBoard
    C:\Users\ユーザ名\AppData\Local\Microsoft_Corporation\DashboardClient.exe_xxxxx\6.1.0.0\user.config

★クライアントログの場所

  • インストールログ
    C:\ProgramData\Microsoft\Windows Server\Logs\ClientDeploy.log
  • Connectorログ
    C:\ProgramData\Microsoft\Windows Server\Logs\Computerconnector.log
  • LaunchPadログ
    C:\ProgramData\Microsoft\Windows Server\Logs\Launchpad.log
  • DashBoardログ
    C:\ProgramData\Microsoft\Windows Server\Logs\DashboardClient.log

調査:LanchPadのログ等を調べる

※2012.01.08 修正

Microsoft Technetフォーラムにて、自分自身のTCP6602を使用するのは仕様とのことを教えていただきました。

パソコンを再起動した後のLaunchpad.logを調べてたところ、どうも自分自身のTCP6602に接続しようとして失敗→リトライしている感じです。

WHS2011の使用期間はある程度ありますが、コアな部分に関してはあまり詳しくないので確実とは言えませんが・・・

Launchpad.logの内容は下記の通りです。


---------------------------------------------------------
[7604] 120107.205913.5934: General: Initializing...C:\Program Files\Windows Server\Bin\Launchpad.exe
[7604] 120107.205913.7544: General: Color branding started
[7604] 120107.205913.7554: General: Processing Microsoft default SKU branding colors XML
[7604] 120107.205913.7574: General: Validating branding colors XML:
<?xml version="1.0"?>

<!-- Vail color scheme -->

<DashboardTheme xmlns="http://www.microsoft.com/HSBS/Dashboard/Branding/2010">

  <!-- Hex color values overwriting default SKU theme colors -->

  <TabstripColor1 HexValue="FFD9EA9C"/>
  <TabstripColor2 HexValue="FF52A22E"/>
  <TabstripColor3 HexValue="00D9EA9C"/>
  <TabstripColor4 HexValue="FFFFFFFF"/>
  <FontColor HexValue="FF000000"/>
  <SubtabColor1 HexValue="FFB1C7A2"/>
  <SubtabColor2 HexValue="FFF7FBE6"/>
  <SubtabColor3 HexValue="FFCEE5A9"/>
  <ListViewColor HexValue="FFFFFFFF"/>
  <DashboardClientColor1 HexValue="FFC2DB67"/>
  <DashboardClientColor2 HexValue="FF8CC65C"/>
  <DashboardClientColor3 HexValue="FF094208"/>
  <LaunchpadColor1 HexValue="FF7FC15A"/>
  <ClientFontColor1 HexValue="FFFFFFFF"/>
  <ClientFontColor2 HexValue="FF000000"/>
  <ClientGlyphColor HexValue="FFFFFFFF"/>

</DashboardTheme >
[7604] 120107.205913.7684: General: Branding colors XML parsing started
[7604] 120107.205913.7684: General: Branding colors XML parsing ended
[7604] 120107.205913.7684: General: Looking for OEM branding colors XML
[7604] 120107.205913.7694: General: No OEM informations available
[7604] 120107.205913.7694: General: Color branding complete
[1144] 120107.205913.7704: ProviderFramework: Information: [0] : Register to listen to ProviderRegistryConnectionMgmt connected event.
[1144] 120107.205913.7744: ProviderFramework: Information: [0] : ProviderRegistryProxy: Beginning connection attempt.
[1144] 120107.205913.7864: ProviderFramework: Information: [0] : ProviderRegistryProxy: Creating proxy for AutoReconnecter.
[1144] 120107.205913.7994: ProviderFramework: Information: [0] : ConnectionMgmt: _CreateChannel address generated = [net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry]
[1144] 120107.205913.8014: ProviderFramework: Information: [0] : GetDuplexChannelFactory()
[1144] 120107.205913.8044: ProviderFramework: Information: [0] : Contract: [Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry]
[1144] 120107.205913.8044: ProviderFramework: Information: [0] : Address: [net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry]
[1144] 120107.205913.8044: ProviderFramework: Information: [0] : Binding: []
[1144] 120107.205913.8054: ProviderFramework: Information: [0] : Identifier: []
[1144] 120107.205913.8054: ProviderFramework: Information: [0] : ProviderEndpointBehavior.AllowedConnectionType: [AllowRemoteAccess]
[1144] 120107.205913.8054: ProviderFramework: Information: [0] : ProviderEndpointBehavior.EndpointCredentialType: [None]
[1144] 120107.205913.8054: ProviderFramework: Information: [0] : RequiredImpersonationLevel: [Identification]
[1144] 120107.205913.8454: PfBinding: Information: [0] : Adding service dns identity [クライアント名] in proxy endpoint.
[1144] 120107.205913.8564: ProviderFramework: Information: [0] : factory impersonation level is: [Identification]
[1144] 120107.205913.9024: ProviderFramework: Information: [0] : ConnectAsync was NOT able to synchronously connect to the provider registry.
[1144] 120107.205913.9094: ServerDiscoveryObjectModel:Backend: Connect: Calling Connector.Connect()
[7604] 120107.205913.9094: General: Restoring window location settings
[1144] 120107.205913.9154: ProviderFramework: Information: [0] : (current thread: 0x478): PfSynchronizationContext not needed.
[7604] 120107.205913.9154: General: Location string: 759|175|280|400|Normal|899|375|{X=0,Y=0,Width=1600,Height=900}{X=0,Y=40,Width=1600,Height=860}True
[1144] 120107.205913.9164: ProviderFramework: Information: [0] : ProviderConnector: Querying for provider info: Microsoft.WindowsServerSolutions.Networking.ServerDiscovery.IServerPresenceProvider, False,
[7604] 120107.205913.9184: General: Window location settings restored successfully
[5012] 120107.205914.0554: ClientSetup: NetGetJoinInformation (server = [], ...)
[7604] 120107.205914.0634: LaunchPad: No file matching the '*.launchpad' file spec located in 'C:\Program Files\Windows Server\Bin\Launchpad'
[7604] 120107.205914.0694: LaunchPad: Loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\LaunchPadContent.dll
[5012] 120107.205914.0774: ProviderFramework: Information: [0] : (current thread: 0x1394): PfSynchronizationContext not needed.
[5012] 120107.205914.0774: ProviderFramework: Information: [0] : (current thread: 0x1394): PfSynchronizationContext not needed.
[7164] 120107.205914.0794: ProviderFramework: Information: [0] : (current thread: 0x1bfc): PfSynchronizationContext not needed.
[7164] 120107.205914.0794: ProviderFramework: Information: [0] : (current thread: 0x1bfc): PfSynchronizationContext not needed.
[7604] 120107.205914.0914: LaunchPad: Finished loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\LaunchPadContent.dll
[7604] 120107.205914.0924: LaunchPad: Loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\en\LaunchPadContent.resources.dll
[7604] 120107.205914.0924: LaunchPad: Skipping satellite dll: 'C:\Program Files\Windows Server\Bin\Launchpad\en\LaunchPadContent.resources.dll'
[7604] 120107.205914.0924: LaunchPad: Loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\ja\LaunchPadContent.resources.dll
[7604] 120107.205914.0924: LaunchPad: Skipping satellite dll: 'C:\Program Files\Windows Server\Bin\Launchpad\ja\LaunchPadContent.resources.dll'
[7604] 120107.205914.0964: LaunchPad: Scanning for empty categories...
[7604] 120107.205914.1014: LaunchPad: Removing empty category: name='アドイン(_A)' id='Microsoft.Launchpad.AddinCategory'
[7604] 120107.205914.1024: LaunchPad: Data loaded. Categories: 0; Tasks: 4
[7248] 120107.205914.8995: ProviderFramework: Information: [0] : (current thread: 0x1c50): PfSynchronizationContext not needed.
[7248] 120107.205914.8995: ProviderFramework: Information: [0] : ProviderConnector: Querying for provider info: Microsoft.WindowsServerSolutions.Users.IUserInfoProvider, True,
[1144] 120107.205918.9287: ServerConnectivityStatus: Cannot connect to ServerPresenceProvider: System.TimeoutException: Timeout occurred waiting for connection to complete.  Connection attempt is still in progress.
   at Microsoft.WindowsServerSolutions.Common.ProviderFramework.internal.ConnectorInternals.TimeoutWait.ThreadWaitHolder.WaitForTimeout(TimeSpan duration)
   at Microsoft.WindowsServerSolutions.Common.ProviderFramework.ProviderConnector`1.WaitForConnection(TimeSpan duration)
   at Microsoft.WindowsServerSolutions.Networking.ServerDiscovery.ProviderBackendBase`1.Connect(TimeSpan timeout)
   at Microsoft.WindowsServerSolutions.LaunchPad.ServerConnectivityStatus.connectWorker_DoWork(Object sender, DoWorkEventArgs e)
[1144] 120107.205918.9287: ServerDiscoveryObjectModel:Backend: Connect: Calling Connector.Connect()
[3420] 120107.205919.9138: ProviderFramework: Information: [0] : AutoReconnecter: m_co faulted.
[3420] 120107.205919.9298: ProviderFramework: Information: [0] : EndOpen indicated that the connection attempt failed: (EndpointNotFoundException) Could not connect to net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry. The connection attempt lasted for a time span of 00:00:06.0053435. TCP error code 10061: 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602.  ==> (SocketException) 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602
[8048] 120107.205919.9338: ProviderFramework: Information: [0] : ProviderRegistryProxy: connection attempt failed.
[2648] 120107.205923.8680: ProviderFramework: Information: [0] : ProviderRegistryProxy: Creating proxy for AutoReconnecter.
[1144] 120107.205923.9300: ServerConnectivityStatus: Cannot connect to ServerPresenceProvider: System.TimeoutException: Timeout occurred waiting for connection to complete.  Connection attempt is still in progress.
   at Microsoft.WindowsServerSolutions.Common.ProviderFramework.internal.ConnectorInternals.TimeoutWait.ThreadWaitHolder.WaitForTimeout(TimeSpan duration)
   at Microsoft.WindowsServerSolutions.Common.ProviderFramework.ProviderConnector`1.WaitForConnection(TimeSpan duration)
   at Microsoft.WindowsServerSolutions.Networking.ServerDiscovery.ProviderBackendBase`1.Connect(TimeSpan timeout)
   at Microsoft.WindowsServerSolutions.LaunchPad.ServerConnectivityStatus.connectWorker_DoWork(Object sender, DoWorkEventArgs e)
[1144] 120107.205923.9310: ServerDiscoveryObjectModel:Backend: Connect: Calling Connector.Connect()
[1144] 120107.205928.9313: ServerConnectivityStatus: Cannot connect to ServerPresenceProvider: System.TimeoutException: Timeout occurred waiting for connection to complete.  Connection attempt is still in progress.
   at Microsoft.WindowsServerSolutions.Common.ProviderFramework.internal.ConnectorInternals.TimeoutWait.ThreadWaitHolder.WaitForTimeout(TimeSpan duration)
   at Microsoft.WindowsServerSolutions.Common.ProviderFramework.ProviderConnector`1.WaitForConnection(TimeSpan duration)
   at Microsoft.WindowsServerSolutions.Networking.ServerDiscovery.ProviderBackendBase`1.Connect(TimeSpan timeout)
   at Microsoft.WindowsServerSolutions.LaunchPad.ServerConnectivityStatus.connectWorker_DoWork(Object sender, DoWorkEventArgs e)
[2648] 120107.205929.8713: ProviderFramework: Information: [0] : AutoReconnecter: m_co faulted.
[3420] 120107.205929.8763: ProviderFramework: Information: [0] : EndOpen indicated that the connection attempt failed: (EndpointNotFoundException) Could not connect to net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry. The connection attempt lasted for a time span of 00:00:05.9963429. TCP error code 10061: 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602.  ==> (SocketException) 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602
[2648] 120107.205929.8773: ProviderFramework: Information: [0] : ProviderRegistryProxy: connection attempt failed.
[8048] 120107.205931.7024: ProviderFramework: Information: [0] : ProviderRegistryProxy: Creating proxy for AutoReconnecter.
[8048] 120107.205937.7118: ProviderFramework: Information: [0] : AutoReconnecter: m_co faulted.
[3420] 120107.205937.7158: ProviderFramework: Information: [0] : EndOpen indicated that the connection attempt failed: (EndpointNotFoundException) Could not connect to net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry. The connection attempt lasted for a time span of 00:00:06.0013433. TCP error code 10061: 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602.  ==> (SocketException) 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602
[8048] 120107.205937.7168: ProviderFramework: Information: [0] : ProviderRegistryProxy: connection attempt failed.
[2648] 120107.205940.5269: ProviderFramework: Information: [0] : ProviderRegistryProxy: Creating proxy for AutoReconnecter.
[7164] 120107.205944.9012: IDENTITY: Connect return -1 with ProviderNotAvailable
[5012] 120107.205944.9332: General: ErrorCode:-1 UserErrorCode:ProviderNotAvailable
BaseException: Microsoft.WindowsServerSolutions.Users.UserException: Connect

Server stack trace:
   at Microsoft.WindowsServerSolutions.Users.UserInfoManager.Connect()
   at Microsoft.WindowsServerSolutions.Users.UserInfoManager.<>c__DisplayClass12.<IsPartOfServerUsers>b__10()
   at System.Runtime.Remoting.Messaging.StackBuilderSink._PrivateProcessMessage(IntPtr md, Object[] args, Object server, Int32 methodPtr, Boolean fExecuteInContext, Object[]& outArgs)
   at System.Runtime.Remoting.Messaging.StackBuilderSink.PrivateProcessMessage(RuntimeMethodHandle md, Object[] args, Object server, Int32 methodPtr, Boolean fExecuteInContext, Object[]& outArgs)
   at System.Runtime.Remoting.Messaging.StackBuilderSink.AsyncProcessMessage(IMessage msg, IMessageSink replySink)

Exception rethrown at [0]:
   at System.Runtime.Remoting.Proxies.RealProxy.EndInvokeHelper(Message reqMsg, Boolean bProxyCase)
   at System.Runtime.Remoting.Proxies.RemotingProxy.Invoke(Object NotUsed, MessageData& msgData)
   at System.Action.EndInvoke(IAsyncResult result)
   at Microsoft.WindowsServerSolutions.Users.UserInfoManager.IsPartOfServerUsers()
   at Microsoft.WindowsServerSolutions.LaunchPad.MainWindow.requireUserSignInBackgroundWorker_DoWork(Object sender, DoWorkEventArgs e)
[5012] 120107.205944.9352: General: Can not connect to server
[7604] 120107.205944.9582: LaunchPad: No file matching the '*.launchpad' file spec located in 'C:\Program Files\Windows Server\Bin\Launchpad'
[7604] 120107.205944.9592: LaunchPad: Loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\LaunchPadContent.dll
[7604] 120107.205944.9642: LaunchPad: Finished loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\LaunchPadContent.dll
[7604] 120107.205944.9652: LaunchPad: Loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\en\LaunchPadContent.resources.dll
[7604] 120107.205944.9652: LaunchPad: Skipping satellite dll: 'C:\Program Files\Windows Server\Bin\Launchpad\en\LaunchPadContent.resources.dll'
[7604] 120107.205944.9652: LaunchPad: Loading data from file: C:\Program Files\Windows Server\Bin\Launchpad\ja\LaunchPadContent.resources.dll
[7604] 120107.205944.9652: LaunchPad: Skipping satellite dll: 'C:\Program Files\Windows Server\Bin\Launchpad\ja\LaunchPadContent.resources.dll'
[7604] 120107.205944.9652: LaunchPad: Scanning for empty categories...
[7604] 120107.205944.9652: LaunchPad: Removing empty category: name='アドイン(_A)' id='Microsoft.Launchpad.AddinCategory'
[7604] 120107.205944.9662: LaunchPad: Data loaded. Categories: 0; Tasks: 4
[7604] 120107.205945.0362: ProviderFramework: Information: [0] : (current thread: 0x1db4): PfSynchronizationContext not needed.
[7604] 120107.205945.0362: ProviderFramework: Information: [0] : ProviderConnector: Querying for provider info: Microsoft.WindowsServerSolutions.Common.SqmProvider.Services.ISqmProviderService, False, Microsoft.WindowsServerSolutions.SqmProvider
[7604] 120107.205945.1652: AlertFramework: GetLocalMachineSID: The local machine Sid is ホリャララ
[7604] 120107.205945.1832: ProviderFramework: Information: [0] : (current thread: 0x1db4): PfSynchronizationContext not needed.
[7604] 120107.205945.1832: ProviderFramework: Information: [0] : ProviderConnector: Querying for provider info: Microsoft.WindowsServerSolutions.NetworkHealth.AlertFramework.Service.IAlertAgentProviderService, False,
[7604] 120107.205945.2172: Addins: Created new event, setting access
[1700] 120107.205945.9193: ProviderFramework: Information: [0] : (current thread: 0x6a4): PfSynchronizationContext not needed.
[1700] 120107.205945.9203: ProviderFramework: Information: [0] : ProviderConnector: Querying for provider info: Microsoft.WindowsServerSolutions.NetworkHealth.AlertFramework.Service.IAlertManagementProviderService, True,
[3420] 120107.205946.5343: ProviderFramework: Information: [0] : AutoReconnecter: m_co faulted.
[3420] 120107.205946.5363: ProviderFramework: Information: [0] : EndOpen indicated that the connection attempt failed: (EndpointNotFoundException) Could not connect to net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry. The connection attempt lasted for a time span of 00:00:06.0013432. TCP error code 10061: 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602.  ==> (SocketException) 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602
[1628] 120107.205947.8914: ProviderFramework: Information: [0] : ProviderRegistryProxy: connection attempt failed.
[7604] 120107.205949.1124: General: Saving window location settings
[7604] 120107.205949.1154: General: Location string: 1027|196|280|400|Normal|1167|396|{X=0,Y=0,Width=1600,Height=900}{X=0,Y=40,Width=1600,Height=860}True
[7248] 120107.205951.4796: ProviderFramework: Information: [0] : ProviderRegistryProxy: Creating proxy for AutoReconnecter.
[8048] 120107.205955.1388: SqmProvider: SqmProviderClient: conntor.WaitForTimeout happened.
[2648] 120107.205957.4879: ProviderFramework: Information: [0] : AutoReconnecter: m_co faulted.
[3420] 120107.205957.4909: ProviderFramework: Information: [0] : EndOpen indicated that the connection attempt failed: (EndpointNotFoundException) Could not connect to net.tcp://クライアント名:6602//Microsoft.WindowsServerSolutions.Common.ProviderFramework.IProviderRegistry. The connection attempt lasted for a time span of 00:00:06.0013433. TCP error code 10061: 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602.  ==> (SocketException) 対象のコンピューターによって拒否されたため、接続できませんでした。 [クライアントIPv6]:6602
[7164] 120107.205957.4919: ProviderFramework: Information: [0] : ProviderRegistryProxy: connection attempt failed.

LaunchPadは何に使うの?

LaunchPadを使いたい理由はバックアップ機能につきます。

せっかくWHS2011で提供されている機能ですので、有償のバックアップソフトウェアを買うのも馬鹿らしいので。

LaunchPadについては、インストール問題やサインイン問題が結構出ているようなので、最悪はバックアップ機能を見切るのも一つなのかな・・・

今すぐバックアップが必要になったなら、Acronis TrueImage等でやるしかないかもしれません。

今後について

現在Microsoft TechNetのWindows Home Serverフォーラムで質問中ですので、解決にいたってはいません。

時間ができたら、仮想環境上にクライアントを作成し、正常な状態のログを採取してみます。

今回のログとにらめっこすれば、何かがわかるかも・・・

解決でき次第、新しい記事でお知らせします。

Acronis True Image Home 2012 Plus ダウンロード版


※2012.01.08 追加

本件はMicorsoft Technetフォーラムの力を借りて解決することができました。

ご協力いただいた薩摩藩中仙道蕨宿別邸のださっちさん、ありがとうございました。

スポンサーリンク

スポンサーリンク