Re: SynApps sscan record: timestamp issue for BUSY DATA and EXSC fields; causing problem archiving with Phoebus Archive engine

[Kuldeep Joshi via Tech-talk <tech-talk at aps.anl.gov>]

Hello,

As suggested by Kay, I have added the following record to monitor the BUSY field.

record(bi, "ioc:Scan:busyVscan1")

{

  field(INP, "iocA:scan1.BUSY CP")

}

I am loading the scan configuration from the ConfigMenu option in the SynApps. The saveMenu.req file is modified to load records of interest.

In both the listing I am restarting the IOC, loading the config for scan and sequentially executing the scan twice.

It looks like the EXSC field is taking the timestamp of earlier execution. ie when it is going from 0 to 1.

Please find the camonitor listing with and without -tc option for the SMSG, EXSC, BUSY, DATA  and the above bi record. (I am wondering why does the camonitor not print VAL field for this record. does it have something to do with it being bi record??)

output of camonitor without -tc option

iocA:scan1.SMSG               <undefined>  

iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined>  
iocA:scan1.SMSG               <undefined> Waiting for PV\'s to connect  
iocA:scan1.SMSG               <undefined> Waiting for PV\'s to connect  
iocA:scan1.SMSG               <undefined> Waiting for PV\'s to connect  
iocA:scan1.SMSG               <undefined> Waiting for PV\'s to connect  
iocA:scan1.EXSC               <undefined> 1  
iocA:scan1.DATA               2021-09-15 08:45:10.499042 0  
iocA:scan1.BUSY               2021-09-15 08:45:10.499042 1  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 Scanning ...  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 Scanning ...  
iocA:Scan:busyVscan1.VAL 2021-09-15 08:45:10.500086  
iocA:scan1.EXSC               2021-09-15 08:45:10.499042 0  
iocA:scan1.DATA               2021-09-15 08:45:10.499042 1  
iocA:scan1.BUSY               2021-09-15 08:45:10.499042 0  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 SCAN Complete  
iocA:Scan:busyVscan1.VAL 2021-09-15 08:46:52.203727  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 Waiting for PV\'s to connect  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 Waiting for PV\'s to connect  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 Waiting for PV\'s to connect  
iocA:scan1.SMSG               2021-09-15 08:45:10.499042 Waiting for PV\'s to connect  
iocA:scan1.EXSC               2021-09-15 08:45:10.499042 1  
iocA:scan1.DATA               2021-09-15 08:47:28.296152 0  
iocA:scan1.BUSY               2021-09-15 08:47:28.296152 1  
iocA:scan1.SMSG               2021-09-15 08:47:28.296152 Scanning ...  
iocA:scan1.SMSG               2021-09-15 08:47:28.296152 Scanning ...  
iocA:Scan:busyVscan1.VAL 2021-09-15 08:47:28.296945  
iocA:scan1.EXSC               2021-09-15 08:47:28.296152 0  
iocA:scan1.DATA               2021-09-15 08:47:28.296152 1  
iocA:scan1.BUSY               2021-09-15 08:47:28.296152 0  
iocA:scan1.SMSG               2021-09-15 08:47:28.296152 SCAN Complete  
iocA:Scan:busyVscan1.VAL 2021-09-15 08:49:10.102537  

Output of camonitor with -tc option

iocA:scan1.SMSG               (2021-09-15 08:50:28.076077)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.081859)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.081988)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.082216)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.082320)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.082586)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.082650)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.082764)  
iocA:scan1.SMSG               (2021-09-15 08:50:28.082831)  
iocA:scan1.SMSG               (2021-09-15 08:50:46.481425) Waiting for PV\'s to connect  
iocA:scan1.SMSG               (2021-09-15 08:50:46.481854) Waiting for PV\'s to connect  
iocA:scan1.SMSG               (2021-09-15 08:50:46.481904) Waiting for PV\'s to connect  
iocA:scan1.SMSG               (2021-09-15 08:50:46.482007) Waiting for PV\'s to connect  
iocA:scan1.EXSC               (2021-09-15 08:50:46.482081) 1  
iocA:scan1.DATA               (2021-09-15 08:50:46.518113) 0  
iocA:scan1.BUSY               (2021-09-15 08:50:46.518594) 1  
iocA:scan1.SMSG               (2021-09-15 08:50:46.518671) Before Scan FLNK ...  
iocA:Scan:busyVscan1.VAL (2021-09-15 08:50:46.518705)  
iocA:scan1.SMSG               (2021-09-15 08:50:46.765988) Scanning ...  
iocA:scan1.EXSC               (2021-09-15 08:52:28.137851) 0  
iocA:scan1.DATA               (2021-09-15 08:52:28.137899) 1  
iocA:scan1.BUSY               (2021-09-15 08:52:29.516685) 0  
iocA:scan1.SMSG               (2021-09-15 08:52:29.516913) SCAN Complete  
iocA:Scan:busyVscan1.VAL (2021-09-15 08:52:29.516954)  
iocA:scan1.SMSG               (2021-09-15 08:52:54.833398) Waiting for PV\'s to connect  
iocA:scan1.SMSG               (2021-09-15 08:52:54.833823) Waiting for PV\'s to connect  
iocA:scan1.SMSG               (2021-09-15 08:52:54.833897) Waiting for PV\'s to connect  
iocA:scan1.SMSG               (2021-09-15 08:52:54.833932) Waiting for PV\'s to connect  
iocA:scan1.EXSC               (2021-09-15 08:52:54.833956) 1  
iocA:scan1.DATA               (2021-09-15 08:52:54.899508) 0  
iocA:scan1.BUSY               (2021-09-15 08:52:54.900124) 1  
iocA:scan1.SMSG               (2021-09-15 08:52:54.900189) Scanning ...  
iocA:scan1.SMSG               (2021-09-15 08:52:54.900481) Scanning ...  
iocA:Scan:busyVscan1.VAL (2021-09-15 08:52:54.900545)  
iocA:scan1.EXSC               (2021-09-15 08:54:36.753269) 0  
iocA:scan1.DATA               (2021-09-15 08:54:36.753304) 1  
iocA:scan1.BUSY               (2021-09-15 08:54:37.956198) 0  
iocA:scan1.SMSG               (2021-09-15 08:54:37.956255) SCAN Complete  
iocA:Scan:busyVscan1.VAL (2021-09-15 08:54:37.956271)  

Regards,

Kuldeep

PS:

I am using following commit of EPICS base with the patch for CALC$

commit b4fb52bd1e8678aeb99730586b00f0ebd8f32da3 (HEAD -> 7.0)
Author: Michael Davidsaver <mdavidsaver at gmail.com>
Date:   Sun Aug 8 09:11:30 2021 -0700

    Fix long string handling for CALC$

commit e6c802463dc31c94247686e0884e55619ca4f30c
Author: Michael Davidsaver <mdavidsaver at gmail.com>
Date:   Sun Aug 8 09:11:04 2021 -0700

    long CALC$ regressTest

commit 1f88f5fb6f20ec7b5367467e831d9ce951d2c2d5
Author: Michael Davidsaver <mdavidsaver at gmail.com>
Date:   Sun Aug 8 09:06:45 2021 -0700

    cleanup regressTest

commit b777233efb06fa4e988c4f0738b0270dd3d095a3 (origin/HEAD, origin/7.0)
Author: Dirk Zimoch <dirk.zimoch at psi.ch>
Date:   Sun Apr 18 18:04:48 2021 +0200

    Support glob pattern for epicsEnvShow
   
    Add epicsStrnGlobMatch with tests

I am using the following commit of the SScan

commit 04856b2c9c71c4c33a3007db08df8b3ad7e3adbb (HEAD, tag: R2-11-4)

with the modifications as below in the saveData_writeXDR.c

@@ -208,6 +208,7 @@
 #include <dbDefs.h>         /* for PVNAME_STRINGSZ */
 #include <epicsTypes.h>     /* for MAX_STRING_SIZE */
 #include <epicsStdio.h>                /* for epicsSnprintf() */
+#include <shareLib.h>       /* for READONLY */
 

On Tue, Sep 14, 2021 at 1:11 PM Mooney, Tim M. <mooney at anl.gov> wrote:

Hi Kuldeep,

This doesn't look like a normal scan to me.  BUSY should go to 1 promptly after EXSC is set to 1.  I would try monitoring SMSG to verify that the scan is behaving normally - not flailing with a disconnected positioner PV, for example.

Tim Mooney (mooney at anl.gov) (630)252-5417
Beamline Controls Group (www.aps.anl.gov)
Advanced Photon Source, Argonne National Lab

---