Announcement

Collapse
No announcement yet.

Losing IP address for UPnPDevice - Offline

Collapse
X
 
  • Filter
  • Time
  • Show
Clear All
new posts

    Losing IP address for UPnPDevice - Offline

    Dirk
    I figured I'd start a new thread since this is a different issue. My remote device is going offline and I'm not sure if it's because I'm running the plugin remotely on a windows box or is there some other issue.

    Everything is hard wired. My TV is hard wired. HS3 is running as a Linux VM and the windows box is also a VM on the same subnet. If I restart the plugin, everything starts working. Firewall is disabled on my Windows system running the remote PI.

    Thank you.

    PS C:\program files (x86)\HomeSeer HS3> .\HSPI_MediaController.exe server=192.168.1.40
    Connecting to server at 192.168.1.40 with Instance = '' ...
    Connected, waiting to be initialized...
    1/4/2017 10:22:47 PM : InitIO for Instance = found CurrentAppPath = C:\program files (x86)\HomeSeer HS3
    1/4/2017 10:22:47 PM : InitIO for Instance = found HS running on Linux = True
    1/4/2017 10:22:47 PM : InitIO Called for Instance = and running on OS = Win32NT
    1/4/2017 10:22:47 PM : InitIO for Instance = found this plugin running on Linux = False
    1/4/2017 10:22:47 PM : CheckLocalIPv4Address found IP Host = p-vwincctv01
    1/4/2017 10:22:47 PM : CheckLocalIPv4Address found IP Address = fe80::895f:28c:5700:7760%12 with AddressFamily = InterNe
    tworkV6
    1/4/2017 10:22:47 PM : CheckLocalIPv4Address found IP Address = fe80::c12:1ee8:e740:bebd%14 with AddressFamily = InterNe
    tworkV6
    1/4/2017 10:22:47 PM : CheckLocalIPv4Address found IP Address = 192.168.1.21 with AddressFamily = InterNetwork
    1/4/2017 10:22:47 PM : CheckLocalIPv4Address found IP Address = 2001:0:d5c:5a30:c12:1ee8:e740:bebd with AddressFamily =
    InterNetworkV6
    1/4/2017 10:22:47 PM : GetLocalIPv4Address found IP Host = p-vwincctv01
    1/4/2017 10:22:47 PM : GetLocalIPv4Address found IP Address = fe80::895f:28c:5700:7760%12 with AddressFamily = InterNetw
    orkV6
    1/4/2017 10:22:47 PM : GetLocalIPv4Address found IP Address = fe80::c12:1ee8:e740:bebd%14 with AddressFamily = InterNetw
    orkV6
    1/4/2017 10:22:47 PM : GetLocalIPv4Address found IP Address = 192.168.1.21 with AddressFamily = InterNetwork
    1/4/2017 10:22:48 PM : GetLocalIPv4Address found Local IP Address = 192.168.1.21
    1/4/2017 10:22:48 PM : GetLocalIPv4Address found IP Address = 2001:0:d5c:5a30:c12:1ee8:e740:bebd with AddressFamily = In
    terNetworkV6
    1/4/2017 10:22:48 PM : InitIO Called for Instance =
    1/4/2017 10:22:48 PM : Media Controller Plugin Initialized
    1/4/2017 10:22:50 PM : FindUPnPDevice: Attempting to locate all connected. This may take up to 9 seconds.
    1/4/2017 10:23:29 PM : FindUPnPDevice - Discovery succeeded: 7 UPnPDevice(s) found.
    1/4/2017 10:23:29 PM : InitIO Called for Instance = 23456789-1234-1010-8000-104fa8d26af2 and running on OS = Win32NT
    1/4/2017 10:23:31 PM : ConnectionManagerCallBack added for device = XBR-75X940D
    1/4/2017 10:23:31 PM : AvTransportCallback added for device = XBR-75X940D
    1/4/2017 10:23:31 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = XBR-75X940D
    1/4/2017 10:23:31 PM : InitIO Called for Instance = 0dae28f8-46e4-4685-a045-b45aa9327e4b and running on OS = Win32NT
    1/4/2017 10:23:32 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = Family Room TV
    1/4/2017 10:23:33 PM : InitIO Called for Instance = 22d14f77-b8b5-45c3-a1bb-c1218fcc3bb2 and running on OS = Win32NT
    1/4/2017 10:23:35 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = Family Room TV
    1/4/2017 11:22:54 PM : IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =
    1/4/2017 11:23:14 PM : Reachable called for UPnPDevice Family Room TV which is not reachable anymore and set Off-line

    #2
    After 24hrs, when the TV is off there is no issue and the devices stay online. Once I turn on the TV, within a couple of hours the devices went offline. I watch TV over the internet so my TV is moving data (Playstation Vue). Is there a retry parameter for the plugin that if connection is lost, after x minutes retry? If I restart the plugin it reconnects.

    Comment


      #3
      Originally posted by denisl View Post
      After 24hrs, when the TV is off there is no issue and the devices stay online. Once I turn on the TV, within a couple of hours the devices went offline. I watch TV over the internet so my TV is moving data (Playstation Vue). Is there a retry parameter for the plugin that if connection is lost, after x minutes retry? If I restart the plugin it reconnects.
      It is supposed to go off-line when you power the TV down, are you saying you lose connection when it is on? Don't confuse the UPNP part of the TV being off with the TV being in power-safe mode.

      Maybe the log is incomplete .... is HS3 on your Linux box losing communication with the PI?

      The log shows the TV going off-line close to mid-night, I would have assumed this when your turned the TV off. Is it not reconnecting when you turn the TV back on?

      Dirk

      Comment


        #4
        I wanted to verify if it was going offline with the TV off. I restarted the PI and it actually stayed online for a few days. Today it went offline at 3:31PM and I had the TV on at that time. The problem appears to be related to the following message:
        IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =

        I know when the TV is off the remote device still shows online. I can go to the HS device and use the Power button to turn the TV on.

        For some reason it seems like the PI is losing the IP address of the TV.

        PS C:\program files (x86)\HomeSeer HS3> .\HSPI_MediaController.exe server=192.168.1.40
        Connecting to server at 192.168.1.40 with Instance = '' ...
        Connected, waiting to be initialized...
        1/9/2017 11:51:08 PM : InitIO for Instance = found CurrentAppPath = C:\program files (x86)\HomeSeer HS3
        1/9/2017 11:51:08 PM : InitIO for Instance = found HS running on Linux = True
        1/9/2017 11:51:08 PM : InitIO Called for Instance = and running on OS = Win32NT
        1/9/2017 11:51:08 PM : InitIO for Instance = found this plugin running on Linux = False
        1/9/2017 11:51:08 PM : CheckLocalIPv4Address found IP Host = p-vwincctv01
        1/9/2017 11:51:08 PM : CheckLocalIPv4Address found IP Address = fe80::895f:28c:5700:7760%12 with AddressFamily = InterNetworkV6
        1/9/2017 11:51:08 PM : CheckLocalIPv4Address found IP Address = fe80::c12:1ee8:e740:bebd%14 with AddressFamily = InterNetworkV6
        1/9/2017 11:51:08 PM : CheckLocalIPv4Address found IP Address = 192.168.1.21 with AddressFamily = InterNetwork
        1/9/2017 11:51:08 PM : CheckLocalIPv4Address found IP Address = 2001:0:d5c:5a30:c12:1ee8:e740:bebd with AddressFamily = InterNetworkV6
        1/9/2017 11:51:08 PM : GetLocalIPv4Address found IP Host = p-vwincctv01
        1/9/2017 11:51:08 PM : GetLocalIPv4Address found IP Address = fe80::895f:28c:5700:7760%12 with AddressFamily = InterNetworkV6
        1/9/2017 11:51:08 PM : GetLocalIPv4Address found IP Address = fe80::c12:1ee8:e740:bebd%14 with AddressFamily = InterNetworkV6
        1/9/2017 11:51:08 PM : GetLocalIPv4Address found IP Address = 192.168.1.21 with AddressFamily = InterNetwork
        1/9/2017 11:51:08 PM : GetLocalIPv4Address found Local IP Address = 192.168.1.21
        1/9/2017 11:51:08 PM : GetLocalIPv4Address found IP Address = 2001:0:d5c:5a30:c12:1ee8:e740:bebd with AddressFamily = InterNetworkV6
        1/9/2017 11:51:08 PM : InitIO Called for Instance =
        1/9/2017 11:51:09 PM : Media Controller Plugin Initialized
        1/9/2017 11:51:12 PM : FindUPnPDevice: Attempting to locate all connected. This may take up to 9 seconds.
        1/9/2017 11:51:51 PM : FindUPnPDevice - Discovery succeeded: 8 UPnPDevice(s) found.
        1/9/2017 11:51:52 PM : InitIO Called for Instance = 23456789-1234-1010-8000-104fa8d26af2 and running on OS = Win32NT
        1/9/2017 11:51:54 PM : ConnectionManagerCallBack added for device = XBR-75X940D
        1/9/2017 11:51:54 PM : AvTransportCallback added for device = XBR-75X940D
        1/9/2017 11:51:54 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = XBR-75X940D
        1/9/2017 11:51:54 PM : InitIO Called for Instance = 0dae28f8-46e4-4685-a045-b45aa9327e4b and running on OS = Win32NT
        1/9/2017 11:51:56 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = Family Room TV
        1/9/2017 11:51:56 PM : InitIO Called for Instance = 22d14f77-b8b5-45c3-a1bb-c1218fcc3bb2 and running on OS = Win32NT
        1/9/2017 11:52:02 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = Family Room TV
        1/9/2017 11:52:02 PM : InitIO Called for Instance = 2b7e6122-6b96-4743-9177-2c7444565a2a and running on OS = Win32NT
        1/9/2017 11:52:04 PM : ConnectionManagerCallBack added for device = PlayOn: p-vwincctv01
        1/9/2017 11:52:04 PM : ContentDirectoryCallBack added for device = PlayOn: p-vwincctv01
        1/9/2017 11:52:04 PM : CreateUPnPControllers created an instance of UPnPDeviceController for UPnPDevice = PlayOn: p-vwincctv01
        1/9/2017 11:58:30 PM : Error in SetIOEx: Device not found for received event. Event = 2 DeviceRef = 472
        1/9/2017 11:58:34 PM : Error in SetIOEx: Device not found for received event. Event = 2 DeviceRef = 471
        1/9/2017 11:58:37 PM : Error in SetIOEx: Device not found for received event. Event = 2 DeviceRef = 470
        1/10/2017 12:30:51 AM : TransportDied for device = XBR-75X940D
        1/10/2017 12:30:51 AM : UPnP connection to device XBR-75X940D was lost in RenderingControlDied.
        1/10/2017 12:30:51 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/10/2017 12:30:51 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/10/2017 12:30:51 AM : UPnP connection to UPnPDevice XBR-75X940D was lost in ConnectionManagerDied.
        1/10/2017 12:30:51 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Offline Full = False
        1/10/2017 8:09:00 PM : ConnectionManagerCallBack added for device = XBR-75X940D
        1/10/2017 8:09:00 PM : AvTransportCallback added for device = XBR-75X940D
        1/10/2017 11:30:55 PM : IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =
        1/10/2017 11:31:15 PM : Reachable called for UPnPDevice Family Room TV which is not reachable anymore and set Off-line
        1/11/2017 1:01:34 AM : TransportDied for device = XBR-75X940D
        1/11/2017 1:01:34 AM : UPnP connection to device XBR-75X940D was lost in RenderingControlDied.
        1/11/2017 1:01:34 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/11/2017 1:01:34 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/11/2017 1:01:34 AM : UPnP connection to UPnPDevice XBR-75X940D was lost in ConnectionManagerDied.
        1/11/2017 1:01:34 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Offline Full = False
        1/11/2017 7:33:46 PM : ConnectionManagerCallBack added for device = XBR-75X940D
        1/11/2017 7:33:47 PM : AvTransportCallback added for device = XBR-75X940D
        1/12/2017 12:13:50 AM : TransportDied for device = XBR-75X940D
        1/12/2017 12:13:50 AM : UPnP connection to UPnPDevice XBR-75X940D was lost in ConnectionManagerDied.
        1/12/2017 12:13:50 AM : UPnP connection to device XBR-75X940D was lost in RenderingControlDied.
        1/12/2017 12:13:50 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/12/2017 12:13:50 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/12/2017 12:13:50 AM : Disconnect: Disconnected from UPnPDevice - XBR-75X940D and CurrentState = Online Full = False
        1/12/2017 10:15:37 AM : ConnectionManagerCallBack added for device = XBR-75X940D
        1/12/2017 10:15:38 AM : AvTransportCallback added for device = XBR-75X940D
        1/12/2017 11:08:21 AM : IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =
        1/12/2017 11:08:41 AM : Reachable called for UPnPDevice Family Room TV which is not reachable anymore and set Off-line
        1/12/2017 1:08:26 PM : IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =
        1/12/2017 1:08:46 PM : Reachable called for UPnPDevice Family Room TV which is not reachable anymore and set Off-line
        1/12/2017 3:30:52 PM : IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =
        1/12/2017 3:31:12 PM : Reachable called for UPnPDevice Family Room TV which is not reachable anymore and set Off-line

        Comment


          #5
          Originally posted by denisl View Post
          I wanted to verify if it was going offline with the TV off. I restarted the PI and it actually stayed online for a few days. Today it went offline at 3:31PM and I had the TV on at that time. The problem appears to be related to the following message:
          IPAddress for UPnPDevice = Family Room TV has changed. Old = 192.168.1.42. New =

          I know when the TV is off the remote device still shows online. I can go to the HS device and use the Power button to turn the TV on.

          For some reason it seems like the PI is losing the IP address of the TV.
          The log entry might be a bit misleading but the PI loses the device and as such loses the IP address because it thinks the device is gone. All of this is based on multicast actually. Every device MUST send an alive message every x minutes and when that is not received, UPNP assumes the device is dead. When I don't receive alive messages within the agreed time period, my UPNP systems wipes out all knowledge of that device including its IP address and that's what you see in the log.

          The following errors are a bit of concern to me. They may indicate an inconsistancy between the HS DB and the PI's DB. Did you delete any devices manually from the HS devices page?

          1/9/2017 11:58:30 PM : Error in SetIOEx: Device not found for received event. Event = 2 DeviceRef = 472
          1/9/2017 11:58:34 PM : Error in SetIOEx: Device not found for received event. Event = 2 DeviceRef = 471
          1/9/2017 11:58:37 PM : Error in SetIOEx: Device not found for received event. Event = 2 DeviceRef = 470

          Now I don't think they have anything to do with the issue at hand here.

          The log is a log with the debug flag off so it doesn't tell me a whole lot except you lose devices, for as far as I can tell, at the moment when you power them down. Unless you turn the debug flag on, there are no log entries when the devices comes back.

          When it happens, can you take a screenshot of the device table on the PI's config page and also click on "view UPNP devices" and take another screenshot of that table.

          The log for example shows "a part of the TV" going in and out at 11pm, 1pm and 3:30pm. How is this TV connected? Wired or wireless? Any potential network issues?

          Dirk

          Comment


            #6
            Thanks Dirk appreciate your support as always.

            I did delete a device when I first set this up (we discussed in this thread, post #8 and you thought it was cleaned up. If there is something I need to do, please let me know.

            I'll enable debug and collect the logs next time it goes offline and the other data to collect you requested. Is it just the debug check box for the PI? Should I log to disk? The TV is hard wired to a 3com 48 port gigabit switch and HS3 Linux is a VM under ESXi 5.5 and the Win2K server remotely running the PI is also a VM. Communication from HS3 to the Win2K VM is over the virtual network. So no wireless in the mix and no network issues that I've ever noticed.

            The "remote" device definitely went offline today when I had the TV on. I worked from home today and turned the TV on around 12PM and it's been on all day.

            Comment


              #7
              Originally posted by denisl View Post
              Thanks Dirk appreciate your support as always.

              I did delete a device when I first set this up (we discussed in this thread, post #8 and you thought it was cleaned up. If there is something I need to do, please let me know.

              I'll enable debug and collect the logs next time it goes offline and the other data to collect you requested. Is it just the debug check box for the PI? Should I log to disk? The TV is hard wired to a 3com 48 port gigabit switch and HS3 Linux is a VM under ESXi 5.5 and the Win2K server remotely running the PI is also a VM. Communication from HS3 to the Win2K VM is over the virtual network. So no wireless in the mix and no network issues that I've ever noticed.

              The "remote" device definitely went offline today when I had the TV on. I worked from home today and turned the TV on around 12PM and it's been on all day.
              We can look at the errors at another time OR look at the HS references and see if you can find them in the HS devices page. I suspect they lead to dead buttons.

              As to the logging, yes, just the debug log flag on, not the superdebug (too much blabla), the UPNP level as always to errors only. Logging to disk would indeed be the easiest to capture the whole log, just set the flag when you start and don't forget to turn it off before you zip the file and post it.

              You are absolutely right that the remote device went off-line a few times this afternoon, we just need to find out why. Are there other remotes active for that TV, apps from a phone perhaps? Did the screen blank or go into some power saving mode? Just trying to figure out what it might be?

              Dirk

              Comment

              Working...
              X