Re: [mh] misterhouse no longer controlling the lights

Jim, is your MH perhaps running on a Raspberry Pi?  When the SD card on those dies it’s very subtle: many things can work but certain things not. You can even log in, make changes – it looks normal but nothing persists beyond a reboot. 

Sent from my iPhone

> On Nov 16, 2021, at 1:25 PM, hoodcanaljim <[email protected]> wrote:
> 
> 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
> 


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