Announcement

Collapse
No announcement yet.

A couple of issues

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

    A couple of issues

    So after several months of completely flawless performance it appears I'm finally having a couple of issues.

    First, my log is continually full of entries like these:
    4/24/2012 2:59:03 AM SonosController Search for ZonePlayer Bathroom complete.
    4/24/2012 2:59:02 AM SonosController Search for ZonePlayer Family Room complete.
    4/24/2012 2:58:54 AM SonosController Service callbacks established for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController DevicePropertiesCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController AlarmClock ControlCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController GroupManagement ControlCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController ContentDirectoryControlCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController RenderingControlCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController AvTransportCallback added for zoneplayer = Bathroom
    4/24/2012 2:58:54 AM SonosController Callback received for Zone Bathroom with device name = uuid:RINCON_000E5870173601400 and Model = S3
    4/24/2012 2:58:54 AM SonosController Connect: Connecting to ZonePlayer - Bathroom: UDN = uuid:RINCON_000E5870173601400
    4/24/2012 2:58:54 AM SonosController ZonePlayer Bathroom reachable on network. Attempting to reconnect
    4/24/2012 2:58:54 AM SonosController Service callbacks established for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController DevicePropertiesCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController AudioInCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController AlarmClock ControlCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController GroupManagement ControlCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController ContentDirectoryControlCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController RenderingControlCallback added for zoneplayer = Family Room
    4/24/2012 2:58:54 AM SonosController AvTransportCallback added for zoneplayer = Family Room
    4/24/2012 2:58:53 AM SonosController Callback received for Zone Family Room with device name = uuid:RINCON_000E58502D9A01400 and Model = S5
    4/24/2012 2:58:53 AM SonosController Connect: Connecting to ZonePlayer - Family Room: UDN = uuid:RINCON_000E58502D9A01400
    4/24/2012 2:58:53 AM SonosController ZonePlayer Family Room reachable on network. Attempting to reconnect
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in ContentDirectory.
    4/24/2012 2:58:53 AM SonosController Content Directory Callback Died. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in TransportDied.
    4/24/2012 2:58:53 AM SonosController TransportDied. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in RenderingControlDied. Attempting to reconnect...
    4/24/2012 2:58:53 AM SonosController Rendering Control Callback Died. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in GroupManagementDied.
    4/24/2012 2:58:53 AM SonosController GroupManagement Callback Died. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in ZonegroupTopologyDied.
    4/24/2012 2:58:53 AM SonosController ZonegroupTopology Callback Died. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in DeviceProperties.
    4/24/2012 2:58:53 AM SonosController Device Property Callback Died. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController Disconnect: Disconnected from ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Bathroom was lost in AlarmClockDied.
    4/24/2012 2:58:53 AM SonosController AlarmClock Callback Died. ZonePlayer - Bathroom
    4/24/2012 2:58:53 AM SonosController UPnP connection to ZonePlayer Family Room was lost in ContentDirectory.
    4/24/2012 2:58:53 AM SonosController Content Directory Callback Died. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in TransportDied.
    4/24/2012 2:58:52 AM SonosController TransportDied. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in RenderingControlDied. Attempting to reconnect...
    4/24/2012 2:58:52 AM SonosController Rendering Control Callback Died. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in GroupManagementDied.
    4/24/2012 2:58:52 AM SonosController GroupManagement Callback Died. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in ZonegroupTopologyDied.
    4/24/2012 2:58:52 AM SonosController ZonegroupTopology Callback Died. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in DeviceProperties.
    4/24/2012 2:58:52 AM SonosController Device Property Callback Died. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in AudioInCallBack.
    4/24/2012 2:58:52 AM SonosController AudioInDied. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController Disconnect: Disconnected from ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController UPnP connection to ZonePlayer Family Room was lost in AlarmClockDied.
    4/24/2012 2:58:52 AM SonosController AlarmClock Callback Died. ZonePlayer - Family Room
    4/24/2012 2:58:52 AM SonosController Search for ZonePlayer Kitchen complete.
    4/24/2012 2:58:50 AM SonosController Search for ZonePlayer Master Bedroom complete.
    4/24/2012 2:58:43 AM SonosController Service callbacks established for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController DevicePropertiesCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController AlarmClock ControlCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController GroupManagement ControlCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController ContentDirectoryControlCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController RenderingControlCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController AvTransportCallback added for zoneplayer = Kitchen
    4/24/2012 2:58:43 AM SonosController Callback received for Zone Kitchen with device name = uuid:RINCON_000E58701B1401400 and Model = S3
    4/24/2012 2:58:43 AM SonosController Connect: Connecting to ZonePlayer - Kitchen: UDN = uuid:RINCON_000E58701B1401400
    4/24/2012 2:58:43 AM SonosController ZonePlayer Kitchen reachable on network. Attempting to reconnect
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in ContentDirectory.
    4/24/2012 2:58:42 AM SonosController Content Directory Callback Died. ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in TransportDied.
    4/24/2012 2:58:42 AM SonosController TransportDied. ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in RenderingControlDied. Attempting to reconnect...
    4/24/2012 2:58:42 AM SonosController Rendering Control Callback Died. ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in GroupManagementDied.
    4/24/2012 2:58:42 AM SonosController GroupManagement Callback Died. ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in ZonegroupTopologyDied.
    4/24/2012 2:58:42 AM SonosController ZonegroupTopology Callback Died. ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in DeviceProperties.
    4/24/2012 2:58:42 AM SonosController Device Property Callback Died. ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController Disconnect: Disconnected from ZonePlayer - Kitchen
    4/24/2012 2:58:42 AM SonosController UPnP connection to ZonePlayer Kitchen was lost in AlarmClockDied.
    4/24/2012 2:58:42 AM SonosController AlarmClock Callback Died. ZonePlayer - Kitchen
    4/24/2012 2:58:41 AM SonosController Service callbacks established for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController DevicePropertiesCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController AlarmClock ControlCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController GroupManagement ControlCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController ZoneGroupTopology ControlCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController ContentDirectoryControlCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController RenderingControlCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController AvTransportCallback added for zoneplayer = Master Bedroom
    4/24/2012 2:58:41 AM SonosController Callback received for Zone Master Bedroom with device name = uuid:RINCON_000E5870EB2201400 and Model = S3
    4/24/2012 2:58:41 AM SonosController Connect: Connecting to ZonePlayer - Master Bedroom: UDN = uuid:RINCON_000E5870EB2201400
    4/24/2012 2:58:41 AM SonosController ZonePlayer Master Bedroom reachable on network. Attempting to reconnect
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in ContentDirectory.
    4/24/2012 2:58:40 AM SonosController Content Directory Callback Died. ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in TransportDied.
    4/24/2012 2:58:40 AM SonosController TransportDied. ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in RenderingControlDied. Attempting to reconnect...
    4/24/2012 2:58:40 AM SonosController Rendering Control Callback Died. ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in GroupManagementDied.
    4/24/2012 2:58:40 AM SonosController GroupManagement Callback Died. ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in ZonegroupTopologyDied.
    4/24/2012 2:58:40 AM SonosController ZonegroupTopology Callback Died. ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in DeviceProperties.
    4/24/2012 2:58:40 AM SonosController Device Property Callback Died. ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController Disconnect: Disconnected from ZonePlayer - Master Bedroom
    4/24/2012 2:58:40 AM SonosController UPnP connection to ZonePlayer Master Bedroom was lost in AlarmClockDied.
    4/24/2012 2:58:40 AM SonosController AlarmClock Callback Died. ZonePlayer - Master Bedroom
    Looks like it occurs about every 20 minutes or so.

    Second, at least on my bathroom Sonos, I can no longer programatically determine if the sonos is playing.

    For example
    MusicApi = pi.GetMusicAPI("Bathroom")
    hs.WriteLog("music state", MusicApi.PlayerState )
    The returned value is 2 (stopped) even though the bathroom player is clearly playing (and not linked, either).

    Any idea what may be going on?

    #2
    Originally posted by sifuhall View Post
    Any idea what may be going on?
    First guess .... network problems. If the players seem to be doing fine, focus on the PC running homeseer. The log indicates that the UPnP subsystem on your PC lost contact with the players and it is being re-established.

    What OS and HW is Homeseer running on?

    Is this PC connected wireless?

    Are the Sonosplayers wireless?

    One of the things you can do is to turn the "Show failing Pings" option on in the Sonos config page (don't forget to click save) and see if you have problems. In your log, was there any error prior to it showing up? I noted it is at 2:58AM, is it always around that time? How is your "Music Database Settings"? Is it set to "auto Update" at 2AM or is it set to "immediate update"?

    Dirk

    Comment


      #3
      Thanks for the reply.

      Those errors happen every 20 minutes or so and the 2am just happened to be the time I was troubleshooting it and copied the text.

      Network could very well be the issue.

      However, using the sonos app on the desktop (same machine that is running homeseer) I have no issues at all.

      Last week I did upgrade from a workgroup to a domain.

      At the time everything seemed to be working as I could still control the speakers but maybe this is fallout from that?

      My speakers are all wireless (combination of Play3 and Play5s) with a wired Sonos Bridge.

      My machine is running Windows 7 64bit with 4 gb RAM, 2 3ghz dual core processors and is connected wired to the network.

      So how can I determine if it is a network issue? Would changing from a workgroup to a domain cause this?

      Comment


        #4
        Just another thought.

        Around the same time I moved from a workgroup to a domain I also upgraded Sonos to version 3.7 build 17551200f and all of my speakers are running version 3.7 build 17551200e

        Comment


          #5
          Originally posted by sifuhall View Post
          So how can I determine if it is a network issue? Would changing from a workgroup to a domain cause this?
          The change from workgroup to a domain should not affect the plugin in any way, at least not directly. Indirectly it may cause issues on your network, I'd doubt it but it is interesting to note that these problems seem to have appeared at around this change ... or the Sonos SW player update.

          Maybe the first question I should have asked is which plugin version are you using. Are you running 1.0.0.88 or better? If you have something significantly older, you may want to upgrade first.

          Now back to the workgroup/domain, it may affect the player in how it goes out on your network and finds music. Do you have a lot of tracks stored on your LAN that Sonos can play from? Any issues with the player itself on that front.

          Back to the controller running OK .... apart from the log entries, your plugin should do fine as well ... assuming .. these interruptions are small, so the controller may also get affected it may not be as noticeable. Having that said, losing connectivity every 20 minutes clearly points to a problem. Do turn the "show failing ping" flag on, wondering what that would show. If we have no pings failing but UPNP failing we have another problem. The other part of a "network problem" could be "firewall" related. Did anything change in that arena? UPnP relies quite a good bit on broadcasting SSDP messages, if you have a device in your network that "throttles" those, you would get these kind of problems.

          On a final note, the fact that you can't reliably show state on the Bathroom player is a problem. If you pull up the status of all your HS devices, does the player show up as linked or not? Is this a "paired" speaker (only S5 can do that). When you run this script, do you see any errors in the log? The "network" interruptions seen above should not cause this unless it tickles some corner case bug that messes up state. Again, which plugin version are you running, there were some state fixes over the last few months. As a test, if you get into that state, when you stop play, start play on this zone, if you go to the HS status page, does it update the artwork and player state? If so, then we need to focus on the script.

          Dirk

          Comment


            #6
            Thanks for the reply.

            I am running version 1.0.0.88

            I don't have any issues at all playing music.

            I will turn on "show failing ping" tonight when I get home.

            Regarding the issue about the state of the bathroom player, let me give you a bit of background on this.

            I have a script that plays music in the bathroom while I shower. That works with no problem and it does not check the state of the bathroom player.

            Additionally, I have another script tied to the bathroom exhaust fan switch.

            If the bathroom player is playing (state 1) it turns up the volume.
            However, if the bathroom player is not playing it plays an internet radio station.

            This has worked flawlessly for months.

            Recently when I turn on the exhaust fan for my morning shower, instead of making the current music louder it suddenly started playing the internet radio station.

            I did some testing by adding debug lines throughout the script and determined that although the music was playing, the player state returned 2.

            During this time the player is linked with my bedroom sonos player, but I can reproduce this issue consistently even when the player is not linked.

            For example, I use the sonos desktop program to play music on the bathroom player (not linked) and then I run

            hs.WriteLog("music playerstate", MusicApi.PlayerState)
            and it returns 2 even though I can hear the music and see that it is playing in the sonos desktop application.

            Just for reference, here is the code I am using and this worked for months with no issue.

            Public Sub fanOn(ByVal params As Object)
            ' increase bathroom speaker volume when fan turns on/off

            Dim MusicApi As Object, intVol As Integer, blnIncrease As Boolean = True
            Dim intAmount As Integer = 10

            Try
            Dim pi As Object
            pi = hs.Plugin("SONOSCONTROLLER")

            If pi Is Nothing Then
            hs.WriteLog("Script", "empty")
            Exit Sub
            End If

            MusicApi = pi.GetMusicAPI("Bathroom")

            hs.WriteLog("fanOn music playerstate", MusicApi.PlayerState)

            ' only change volume if music playing
            If MusicApi.PlayerState = 1 Then
            'MusicApi.PlayerState values:
            '1 = playing
            '2 = stopped
            '3 = paused
            '4 = forwarding
            '5 = rewinding

            intVol = MusicApi.Volume

            intVol = intVol + intAmount

            MusicApi.Volume = intVol
            Else
            hs.WriteLog("fanOn", "playing music")
            ' play music
            MusicApi.Volume = 30
            MusicApi.PlayMusic("", "", "RadioStation: 181.FM Awesome 80's")
            End If


            Catch ex As Exception
            hs.WriteLog("Script", "Music API not found")
            Exit Sub
            End Try

            End Sub
            I have not checked the artwork to see if it changes, but I will do so tonight.

            Comment


              #7
              Originally posted by sifuhall View Post
              I did some testing by adding debug lines throughout the script and determined that although the music was playing, the player state returned 2.

              During this time the player is linked with my bedroom sonos player, but I can reproduce this issue consistently even when the player is not linked.

              For example, I use the sonos desktop program to play music on the bathroom player (not linked) and then I run and it returns 2 even though I can hear the music and see that it is playing in the sonos desktop application.
              When it happens, have a look at the HS status page, it smells a lot like the plugin is "confused" about the zone being linked or not. Beta .90 that I posted over the weekend would fix an issue if you restart HS on occasion, do you restart HS occasionally? There is another thing to pay attention to, when it happens (and you are linked), who is the group master? Is the bedroom joined to the bathroom or the other way around. One of the things that has caused bug fixes over the last few months have to do with state info of link players, especially those who are non master. Linked-non-master players do not generate any events and issuing play/pause/stop to them won't work so the plugin needs to keep track and forward these commands. This was introduced in v.69 but since then I did run into a few corner cases. Always possible that the latest Sonos SW 3.7 changed something but I'm not aware of any. Now if you link your Bedroom and Bathroom and the Bathroom was always master, you would have never seen a problem, the other way around would have not work prior to v69.

              So first thing to do when you have the issue, check the HS status page. If that page shows the wrong "linked" state, than that would be the reason. In that case, you may want to email me your .ini file, I once ran into an issue on my own setup where the info in the .ini file went corrupt and linking/unlinking would confuse the plugin.

              Dirk



              Dirk

              Comment


                #8
                Thanks again for the reply.

                I do not believe this plug-in had anything to do with my issue.

                I rebooted and the issue persisted and then I performed a few minor Windows 7 updates and rebooted again and miraculously this issue resolved itself.

                It has been over 24 hours now and I'm still not able to recreate this issue so I don't know if it was an update, multiple reboots, or the combination of the two I no longer have this issue.

                Comment

                Working...
                X