Announcement

Collapse
No announcement yet.

Performance Issue with HS Web Interface

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

    Performance Issue with HS Web Interface

    Issue:
    The client GUI performance is acceptable with a 1 to 2 second latency, but the web interface has a latency of about 10 to 20 seconds between selecting anything. Homeseer.exe spikes the CPU to 100% during these periods of latency. Is this behavior normal.

    Environment:
    Windows XP SP2
    CPU Pentium III 930 MHz
    RAM 512 MB
    HomeSeer 1.7.3
    HSPhone 2.0.17
    ISP: DSL ~ 2 Mbs down / 300 kbs up

    Below is a section of the hstrace.log that was captured when the slowdown is occuring, but I am not sure what I can derive from it or what I can do about it. Any help interpreting this trace would be appreciated.


    6/15/2005 9:55:31 AM:35731.86: Enter tcp_start_send->INDEX: 1 Len: 4897
    6/15/2005 9:55:31 AM:35731.86: tcp_start_send->Calling Write1
    6/15/2005 9:55:31 AM:35731.89: tcp_start_send->Returned from Write1-Len: -1
    6/15/2005 9:55:31 AM:35731.89: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:31 AM:35731.94: Enter tcp_start_send->INDEX: 1 Len: 542
    6/15/2005 9:55:31 AM:35731.94: tcp_start_send->Calling Write2
    6/15/2005 9:55:31 AM:35731.94: tcp_start_send->Returned from Write2-Len: -1
    6/15/2005 9:55:31 AM:35731.95: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:31 AM:35731.95: Enter tcp_start_send->INDEX: 1 Len: 762
    6/15/2005 9:55:31 AM:35731.95: tcp_start_send->Calling Write2
    6/15/2005 9:55:31 AM:35731.95: tcp_start_send->Returned from Write2-Len: -1
    6/15/2005 9:55:31 AM:35731.95: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:31 AM:35731.96: Enter tcp_start_send->INDEX: 1 Len: 1236
    6/15/2005 9:55:31 AM:35731.96: tcp_start_send->Calling Write2
    6/15/2005 9:55:31 AM:35731.97: tcp_start_send->Returned from Write2-Len: -1
    6/15/2005 9:55:31 AM:35731.98: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:31 AM:35732: Enter tcp_start_send->INDEX: 1 Len: 1238
    6/15/2005 9:55:31 AM:35732: tcp_start_send->Calling Write2
    6/15/2005 9:55:31 AM:35732: tcp_start_send->Returned from Write2-Len: -1
    6/15/2005 9:55:31 AM:35732: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:31 AM:35732: Enter tcp_start_send->INDEX: 1 Len: 1233
    6/15/2005 9:55:31 AM:35732: tcp_start_send->Calling Write2
    6/15/2005 9:55:32 AM:35732.01: tcp_start_send->Returned from Write2-Len: -1
    6/15/2005 9:55:32 AM:35732.01: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:32 AM:35732.03: Exit GetStatusPage->INDEX 1
    6/15/2005 9:55:32 AM:35732.03: Enter tcp_start_send->INDEX: 1 Len: 111
    6/15/2005 9:55:32 AM:35732.03: tcp_start_send->Calling Write2
    6/15/2005 9:55:32 AM:35732.04: tcp_start_send->Returned from Write2-Len: -1
    6/15/2005 9:55:32 AM:35732.04: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:32 AM:35732.04: Exit tcpserver_Read->Index: 1
    6/15/2005 9:55:32 AM:35732.04: Enter tcpserver_Read->index: 1 Len: 363
    6/15/2005 9:55:32 AM:35732.04: tcpserver_Read->GET GET /stat?location=Office HTTP/1.1
    6/15/2005 9:55:32 AM:35732.05: tcpserver_Read->get page /stat?location=Office
    6/15/2005 9:55:32 AM:35732.05: Enter GetStatusPage->INDEX 1
    6/15/2005 9:55:32 AM:35732.05: Enter SaveSetting->gSelectedLocationWeb
    6/15/2005 9:55:32 AM:35732.05: Exit SaveSetting->gSelectedLocationWeb
    6/15/2005 9:55:32 AM:35732.08: Enter tcp_start_send->INDEX: 1 Len: 139
    6/15/2005 9:55:32 AM:35732.09: tcp_start_send->Calling Write2
    6/15/2005 9:55:32 AM:35732.09: tcp_start_send->Returned from Write2-Len: 139
    6/15/2005 9:55:32 AM:35732.09: Missed time, catching up: 18 timeval: 6/15/2005 9:55:32 AM Glast: 6/15/2005 9:55:14 AM
    6/15/2005 9:55:32 AM:35732.09: Enter HandleTimeChange
    6/15/2005 9:55:32 AM:35732.09: Exit HandleTimeChange
    6/15/2005 9:55:32 AM:35732.1: Missed time, catching up: 17 timeval: 6/15/2005 9:55:32 AM Glast: 6/15/2005 9:55:15 AM
    6/15/2005 9:55:32 AM:35732.1: Enter HandleTimeChange
    6/15/2005 9:55:32 AM:35732.1: Exit HandleTimeChange
    6/15/2005 9:55:32 AM:35732.11: Exit tcp_start_send->INDEX: 1
    6/15/2005 9:55:49 AM:35749.13: Enter tcp_start_send->INDEX: 1 Len: 4897
    6/15/2005 9:55:49 AM:35749.13: tcp_start_send->Calling Write1
    6/15/2005 9:55:49 AM:35749.14: tcp_start_send->Returned from Write1-Len: 4000
    6/15/2005 9:55:49 AM:35749.15: Missed time, catching up: 33 timeval: 6/15/2005 9:55:49 AM Glast: 6/15/2005 9:55:16 AM
    6/15/2005 9:55:49 AM:35749.15: Enter HandleTimeChange
    6/15/2005 9:55:49 AM:35749.15: Exit HandleTimeChange
    6/15/2005 9:55:49 AM:35749.15: Enter Plugin CheckInterfaces
    6/15/2005 9:55:49 AM:35749.16: Exit Plugin CheckInterfaces
    6/15/2005 9:55:49 AM:35749.17: Enter tcpserver_Read->index: 2 Len: 316
    6/15/2005 9:55:49 AM:35749.17: tcpserver_Read->GET GET /style.css HTTP/1.1
    6/15/2005 9:55:49 AM:35749.18: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.18: Enter tcp_start_send->INDEX: 2 Len: 3589
    6/15/2005 9:55:49 AM:35749.18: tcp_start_send->Calling Write2
    6/15/2005 9:55:49 AM:35749.18: tcp_start_send->Returned from Write2-Len: 3589
    6/15/2005 9:55:49 AM:35749.19: Enter tcpserver_Read->index: 3 Len: 316
    6/15/2005 9:55:49 AM:35749.2: tcpserver_Read->GET GET /clock.gif HTTP/1.1
    6/15/2005 9:55:49 AM:35749.22: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.22: Enter tcp_start_send->INDEX: 3 Len: 1037
    6/15/2005 9:55:49 AM:35749.22: tcp_start_send->Calling Write2
    6/15/2005 9:55:49 AM:35749.22: tcp_start_send->Returned from Write2-Len: 1037
    6/15/2005 9:55:49 AM:35749.22: Enter tcpserver_Read->index: 4 Len: 318
    6/15/2005 9:55:49 AM:35749.22: tcpserver_Read->GET GET /sunrise.gif HTTP/1.1
    6/15/2005 9:55:49 AM:35749.23: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.24: Enter tcp_start_send->INDEX: 4 Len: 1006
    6/15/2005 9:55:49 AM:35749.24: tcp_start_send->Calling Write2
    6/15/2005 9:55:49 AM:35749.24: tcp_start_send->Returned from Write2-Len: 1006
    6/15/2005 9:55:49 AM:35749.25: Enter tcpserver_Read->index: 5 Len: 317
    6/15/2005 9:55:49 AM:35749.25: tcpserver_Read->GET GET /sunset.gif HTTP/1.1
    6/15/2005 9:55:49 AM:35749.26: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.27: Enter tcp_start_send->INDEX: 5 Len: 997
    6/15/2005 9:55:49 AM:35749.27: tcp_start_send->Calling Write2
    6/15/2005 9:55:49 AM:35749.27: tcp_start_send->Returned from Write2-Len: 997
    6/15/2005 9:55:49 AM:35749.27: Missed time, catching up: 32 timeval: 6/15/2005 9:55:49 AM Glast: 6/15/2005 9:55:17 AM
    6/15/2005 9:55:49 AM:35749.27: Enter HandleTimeChange
    6/15/2005 9:55:49 AM:35749.28: Exit HandleTimeChange
    6/15/2005 9:55:49 AM:35749.28: Enter tcpserver_Read->index: 6 Len: 318
    6/15/2005 9:55:49 AM:35749.28: tcpserver_Read->GET GET /kesmall.jpg HTTP/1.1
    6/15/2005 9:55:49 AM:35749.3: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.3: Enter tcp_start_send->INDEX: 6 Len: 5742
    6/15/2005 9:55:49 AM:35749.3: tcp_start_send->Calling Write1
    6/15/2005 9:55:49 AM:35749.31: tcp_start_send->Returned from Write1-Len: 4000
    6/15/2005 9:55:49 AM:35749.32: Enter tcpserver_Read->index: 7 Len: 323
    6/15/2005 9:55:49 AM:35749.32: tcpserver_Read->GET GET /new_messages.gif HTTP/1.1
    6/15/2005 9:55:49 AM:35749.32: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.32: Enter tcp_start_send->INDEX: 7 Len: 267
    6/15/2005 9:55:49 AM:35749.32: tcp_start_send->Calling Write2
    6/15/2005 9:55:49 AM:35749.33: tcp_start_send->Returned from Write2-Len: 267
    6/15/2005 9:55:49 AM:35749.33: Enter tcpserver_Read->index: 8 Len: 321
    6/15/2005 9:55:49 AM:35749.33: tcpserver_Read->GET GET /background.gif HTTP/1.1
    6/15/2005 9:55:49 AM:35749.33: Enter TCPSendFile->fname
    6/15/2005 9:55:49 AM:35749.34: Enter tcp_start_send->INDEX: 8 Len: 11854
    6/15/2005 9:55:49 AM:35749.34: tcp_start_send->Calling Write1
    6/15/2005 9:55:49 AM:35749.34: tcp_start_send->Returned from Write1-Len: 4000
    6/15/2005 9:55:49 AM:35749.34: Missed time, catching up: 31 timeval: 6/15/2005 9:55:49 AM Glast: 6/15/2005 9:55:18 AM
    6/15/2005 9:55:49 AM:35749.34: Enter HandleTimeChange
    6/15/2005 9:55:49 AM:35749.34: Exit HandleTimeChange
    6/15/2005 9:55:49 AM:35749.35: Missed time, catching up: 30 timeval: 6/15/2005 9:55:49 AM Glast: 6/15/2005 9:55:19 AM
    6/15/2005 9:55:49 AM:35749.35: Enter HandleTimeChange
    6/15/2005 9:55:49 AM:35749.35: Exit HandleTimeChange
    6/15/2005 9:55:49 AM:35749.35: tcp_start_send->Calling Write1
    6/15/2005 9:55:49 AM:35749.35: tcp_start_send->Returned from Write1-Len: 4000
    6/15/2005 9:55:49 AM:35749.36: tcp_start_send->Calling Write2
    6/15/2005 9:55:49 AM:35749.36: tcp_start_send->Returned from Write2-Len: 3854
    6/15/2005 9:55:49 AM:35749.36: Missed time, catching up: 29 timeval: 6/15/2005 9:55:49 AM Glast: 6/15/2005 9:55:20 AM

    #2
    It is typical for me. My usual interface is with the GUI, but when I do use the browser it is sluggish.

    Comment


      #3
      Can you give an example of a process that is taking 20 seconds? Like I select a light on command etc... I use the web interface exclusively and I rarely see more than a 1 second delay in any process. If your computer is going to 100% alot then you need to figure out what is taking it there and for how long. Are you running a lot of plugins? Are you running scripts that scrape web sites? How about checking email and how often. All of these can cause HS to "freeze" for a second or 2 while completing the task.
      💁‍♂️ Support & Customer Service 🙋‍♂️ Sales Questions 🛒 Shop HomeSeer Products

      Comment


        #4
        In my case a simple display of device status page is typically more than 10 seconds. No button presses or anything. PC running HS is 2.8G P4 / 1G RAM Dell PowerServer series.

        Comment


          #5
          I disabled e-mail checking, but I do have quite a few devices and events. I do use TTS and it performs well. I do not have scripts that scrape web pages, but I do have a few plugins. Are their any plugins that would degrade web interface performance, but not the client GUI interface performance?

          The section of the log above captured what homeseer was doing when I was viewing the device list and then selected a new location from the dropdown box. The new location only had five devices assigned to it. I get the same response time of about 20 seconds when I select the All Locations options from the drop down. The view then returns about 100 devices.

          Normal homeseer.exe CPU utilization is about 5%, and rarely goes above 50%. However using the web interface it spikes to 100% from 10 - 20 seconds on all user actions. Turning a device on or off. Selecting a menu link. Selecting a value from a dropdown. Running an event, etc. Again this performance degradation only occurs via the Web Interface.

          Comment


            #6
            Gee... I'm running on a PII 450 and my web performance is very quick. I have 4 plugins installed and about 12 devices plus my txb16 thermostat. Cpu sits mostly at 1% and only marginally spikes to 5-7% on any web access. The system also runs my mailserver, dns primary server, ftp and webserver with openssl.
            I am about to get into the WF800 world and am causiouly wondering about the impact of plugins on performance. I'd hate to break something going forward and am wondering about plugin interaction/compatibility.
            Currently I have web wrapper, Jone's Ultralog, Jim's RCS X10 TXB plugin and performance monitor. I do have HS running as a service on WIndows 2000 server SP4.

            Rob
            HS3PRO 3.0.0.500 as a Fire Daemon service, Windows 2016 Server Std Intel Core i5 PC HTPC Slim SFF 4GB, 120GB SSD drive, WLG800, RFXCom, TI103,NetCam, UltraNetcam3, BLBackup, CurrentCost 3P Rain8Net, MCsSprinker, HSTouch, Ademco Security plugin/AD2USB, JowiHue, various Oregon Scientific temp/humidity sensors, Z-Net, Zsmoke, Aeron Labs micro switches, Amazon Echo Dots, WS+, WD+ ... on and on.

            Comment


              #7
              Resolution:

              I unchecked the E-mail Link from the Web Server Configuration tab and my Web Interface response time went from 20 seconds to 1 second.

              Comment


                #8
                Yep. That's why I use Outlook Express to do the work of checking for new emails and then have HS simply use MAPI to check for new mail every 5 minutes.
                💁‍♂️ Support & Customer Service 🙋‍♂️ Sales Questions 🛒 Shop HomeSeer Products

                Comment

                Working...
                X