[mh] misterhouse no longer controlling the lights

Hi

  Last night I found that MH had stopped controlling my system.  We have
had several power outages lately and there was one yesterday around
noon.  First noticed the problem when I tried to shut off the
$familyroom light.  In the web interface $familyroom shows as a green
bar (8088/ia7/) with the word "on" but when opening that link it wont
take a on command but with a off command it closes as expected but the
web interface still shows it as "on".
  I have a second PLM, not sure what its status is,  I am not seeing
anything in the status command or in the print.log that I can recognize
as a problem.

  Main question: Is there a way to test the PLM thru MH that can tell me
if its Blown?

Biggest problem: I haven't had to work on this system in several years
and I don't remember half of what I learned.  So I am turning to your
group for help to keep me from doing something to crash the whole system.
Thanks
Jim


systemctl status mhouse -l
● mhouse.service - Misterhouse Home Automation
    Loaded: loaded (/etc/systemd/system/mhouse.service; enabled; vendor
preset: disabled)
    Active: active (running) since Tue 2021-11-16 09:42:02 PST; 21min ago
  Main PID: 3542 (mh)
     Tasks: 1
    CGroup: /system.slice/mhouse.service
            └─3542 /usr/bin/perl /opt/mista/mh/bin/mh -tk 0

Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Rereading .menu code
files.
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Organizer: Calendar
matches target schema and does not require upgrading
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Organizer: Todo
matches target schema and does not require upgrading
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Organizer: Reading
updated organizer calendar file now
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Evaluating code
organizer_events
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Organizer: Reading
updated organizer todo file
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Evaluating code
organizer_tasks
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM Family turned on
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM [Insteon::BaseObject]
$yardlight2::set(OFF, )
Nov 16 09:42:03 nub mh[3542]: 11/16/21 09:42:03 AM [Insteon::BaseObject]
$yardlight3::set(OFF, )


print.log
11/16/21 09:39:58 AM [Insteon_PLM] DEBUG2: Sending obj=$garage_iolinc;
command=set_operating_flags; extra=06 incurred delay of 3.45 seconds;
starting hop-count: 3
11/16/21 09:39:58 AM [Insteon_PLM] DEBUG3: Sending  PLM raw data:
0262348b560f2006
11/16/21 09:39:58 AM [Insteon_PLM] DEBUG4:
          PLM Command: (0262) insteon_send
               To Address: 34:8b:56
            Message Flags: 0f
                 Message Type: (000) Direct Message
               Message Length: (0) Standard Length
                    Hops Left: 3
                     Max Hops: 3
          Insteon Message: 2006
                        Cmd 1: (20) Set Operating Flags
                        Cmd 2: (06) Deveice Dependent

11/16/21 09:40:00 AM [Insteon::BaseMessage] WARN: now resending
obj=$garage_iolinc; command=set_operating_flags; extra=06 after 3 attempts.
11/16/21 09:40:00 AM [Insteon_PLM] DEBUG2: Sending obj=$garage_iolinc;
command=set_operating_flags; extra=06 incurred delay of 5.49 seconds;
starting hop-count: 3
11/16/21 09:40:00 AM [Insteon_PLM] DEBUG3: Sending  PLM raw data:
0262348b560f2006
11/16/21 09:40:00 AM [Insteon_PLM] DEBUG4:
          PLM Command: (0262) insteon_send
               To Address: 34:8b:56
            Message Flags: 0f
                 Message Type: (000) Direct Message
               Message Length: (0) Standard Length
                    Hops Left: 3
                     Max Hops: 3
          Insteon Message: 2006
                        Cmd 1: (20) Set Operating Flags
                        Cmd 2: (06) Deveice Dependent

11/16/21 09:40:02 AM [Insteon::BaseMessage] WARN: now resending
obj=$garage_iolinc; command=set_operating_flags; extra=06 after 4 attempts.
11/16/21 09:40:02 AM [Insteon_PLM] DEBUG2: Sending obj=$garage_iolinc;
command=set_operating_flags; extra=06 incurred delay of 7.51 seconds;
starting hop-count: 3
11/16/21 09:40:02 AM [Insteon_PLM] DEBUG3: Sending  PLM raw data:
0262348b560f2006
11/16/21 09:40:02 AM [Insteon_PLM] DEBUG4:
          PLM Command: (0262) insteon_send
               To Address: 34:8b:56
            Message Flags: 0f
                 Message Type: (000) Direct Message
               Message Length: (0) Standard Length
                    Hops Left: 3
                     Max Hops: 3
          Insteon Message: 2006
                        Cmd 1: (20) Set Operating Flags
                        Cmd 2: (06) Deveice Dependent

11/16/21 09:40:04 AM [Insteon::BaseInterface] WARN: number of retries
(5) for obj=$garage_iolinc; command=set_operating_flags; extra=06
exceeds limit.  Now moving on...
11/16/21 09:40:04 AM [Insteon_PLM] DEBUG2: Sending obj=$PLM;
interface_data= incurred delay of 9.37 seconds; starting hop-count: ?
11/16/21 09:40:04 AM [Insteon_PLM] DEBUG3: Sending  PLM raw data: 0260
11/16/21 09:40:04 AM [Insteon_PLM] DEBUG4:
          PLM Command: (0260) plm_info

11/16/21 09:42:03 AM ---------- Restart ----------
11/16/21 09:42:03 AM [Insteon_PLM] serial:/dev/ttyUSB0:19200
11/16/21 09:42:03 AM [IA7_Collection_Updater] : Starting
11/16/21 09:42:03 AM [IA7_Collection_Updater] : Reviewing
./../data/web/collections.json to current version 1.4
11/16/21 09:42:03 AM [IA7_Collection_Updater] : Finished
11/16/21 09:42:03 AM Perl @INC contains: /opt/mista/code/common/,
./../code/common, /opt/mista/mh/bin/../lib,
/opt/mista/mh/bin/../lib/site, ., /usr/local/lib64/perl5,
/usr/local/share/perl5, /usr/lib64/perl5/vendor_perl,
/usr/share/perl5/vendor_perl, /usr/lib64/perl5, /usr/share/perl5, .
11/16/21 09:42:03 AM Reading /opt/mista/mh.mine.ini and mh.ini
11/16/21 09:42:03 AM Reading 1 .mht table files: insteon.mht
11/16/21 09:42:03 AM Translating insteon.mht ->
/opt/mista/code/common//insteon.mhp
11/16/21 09:42:03 AM Initialized read_table_A.pl
11/16/21 09:42:03 AM Reading 16 code files
11/16/21 09:42:03 AM Evaluating user code
11/16/21 09:42:03 AM [Insteon_PLM] 2412[US] using serial,
serial_port=/dev/ttyUSB0
11/16/21 09:42:03 AM [Insteon] Setting up initialization hooks
11/16/21 09:42:03 AM [Insteon_PLM] setting default xmit delay to: 0.15
11/16/21 09:42:03 AM [Insteon_PLM] setting x10 xmit delay to: 0.5
11/16/21 09:42:03 AM FUNCTION: set_volume_pre_hook
11/16/21 09:42:03 AM running: play ./../sounds/sound_click1.wav

11/16/21 09:42:03 AM IA7 Speech Notifications enabled
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version of all devices
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for
$porchlight2 (1 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for
$yardlight3 (2 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for
$yardlight2 (3 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for
$garage_in (4 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for $famroom
(5 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for
$porchlight1 (6 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version for
$garage_iolinc (7 of 7)
11/16/21 09:42:03 AM [Insteon::BaseDevice] DEBUG4: aldb_version is I1
but device is I2CS.  Remapping aldb version to I2
11/16/21 09:42:03 AM [Insteon] DEBUG4 Checking aldb version of all
devices completed
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$garage_iolinc; command=set_operating_flags; extra=06 after 1 attempts.
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$garage_iolinc; command=set_operating_flags; extra=06 after 2 attempts.
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$garage_iolinc; command=set_operating_flags; extra=06 after 3 attempts.
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$garage_iolinc; command=set_operating_flags; extra=06 after 4 attempts.
11/16/21 09:42:03 AM [Insteon::BaseInterface] WARN: number of retries
(5) for obj=$garage_iolinc; command=set_operating_flags; extra=06
exceeds limit.  Now moving on...
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$PLM; interface_data= after 1 attempts.
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$PLM; interface_data= after 2 attempts.
11/16/21 09:42:03 AM [Insteon::BaseMessage] WARN: now resending
obj=$PLM; interface_data= after 3 attempts.
11/16/21 09:42:03 AM [Insteon] DEBUG4 Initializing thermostat versions
11/16/21 09:42:03 AM Generating Voice commands for all Insteon objects
11/16/21 09:42:03 AM Rereading .menu code files.
11/16/21 09:42:03 AM Organizer: Calendar matches target schema and does
not require upgrading
11/16/21 09:42:03 AM Organizer: Todo matches target schema and does not
require upgrading
11/16/21 09:42:03 AM Organizer: Reading updated organizer calendar file now
11/16/21 09:42:03 AM Evaluating code organizer_events
11/16/21 09:42:03 AM Organizer: Reading updated organizer todo file
11/16/21 09:42:03 AM Evaluating code organizer_tasks
11/16/21 09:42:03 AM Family turned on
11/16/21 09:42:03 AM [Insteon::BaseObject] $yardlight2::set(OFF, )
11/16/21 09:42:03 AM [Insteon::BaseObject] $yardlight3::set(OFF, )


________________________________________________________
To unsubscribe from this list, go to: https://lists.sourceforge.net/lists/listinfo/misterhouse-users