
  2026-09-14
---------------
14-09:33:20.253   Graphical User Interface started.
14-09:33:20.254   Operating system = LINUX, JVM type (bit) = 64

14-09:33:20.254   User properties directory=/home/nmrafd/.topspin-av600/prop
14-09:33:20.772   GUI Version = TopSpin 3.7.0 / local
14-09:33:20.863   cmd=_create_drag_tranfer_handler
14-09:33:21.010   To connect to this TOPSPIN instance via Remote Connection use host=132.229.121.209, port=5501
14-09:33:21.010   Start: opening file for local session key at '/opt/topspin3.7.0/prog/curdir/nmrafd/keystore/localSession.key'
14-09:33:21.011   Start: local session key imported successfully.
14-09:33:21.011   Start: GUI session key created
14-09:33:21.012   cmd=init_cpr
14-09:33:21.013   Start ORB: recommended port=5090.
14-09:33:21.067   Start ORB: successful on port 5090
14-09:33:21.067   cmd=cprlistener
14-09:33:21.069   Start CprListenerServer: activate.
14-09:33:21.088   Start CprListenerServer: activation successful, id = corbaloc:iiop:132.229.121.209:5090/4346494571/CtrlPOA/CPRClient, localized id = corbaloc:iiop:127.0.0.1:5090/4346494571/CtrlPOA/CPRClient
14-09:33:21.270   Contact configuration service: URL=corbaloc:iiop:132.229.121.209:5501/ConfigurationServer, IOR=IOR:010000002400000049444c3a6272756b65722e64652f6370722f436f6e66696775726174696f6e3a312e3000010000000000000064000000010102000a0000003132372e302e302e31007d1513000000436f6e66696775726174696f6e536572766572000200000000000000080000000100000000545441010000001c00000001000000010001000100000001000105090101000100000009010100
14-09:33:21.270   Contact configuration service: local contact, using URL: corbaloc:iiop:127.0.0.1:5501/ConfigurationServer
14-09:33:21.300   Contact configuration service: successful.
14-09:33:21.300   Contact configuration service: local session, authentication done.
14-09:33:21.303   Contact CPR: local contact, narrowing object for: corbaloc:iiop:127.0.0.1:5501/CPRMsg, IOR=IOR:010000001d00000049444c3a6272756b65722e64652f6370722f4350524d73673a312e3000000000010000000000000058000000010102000a0000003132372e302e302e31007d15060000004350524d736700000200000000000000080000000100000000545441010000001c00000001000000010001000100000001000105090101000100000009010100
14-09:33:21.305   Contact CPR: object is valid.
14-09:33:21.305   Contact CPR: address of CprListener is corbaloc:iiop:132.229.121.209:5090/4346494571/CtrlPOA/CPRClient
14-09:33:21.305   Contact CPR: login at CPR with "corbaloc:iiop:127.0.0.1:5090/4346494571/CtrlPOA/CPRClient"
14-09:33:21.556   Contact CPR: attempt #0 to get assigned client id...
14-09:33:21.556   Contact CPR: successful, got id '1'.
14-09:33:21.557   CPR connection successful, client id = 1
14-09:33:21.558   Start dataserver: launching command, waiting on result
14-09:33:21.559   to CPR: "dataserver id 1 1789371201559" [id 1], COMMAND_WAIT_START
14-09:33:21.600   CPR task 1 resumed normally, termstat=0
14-09:33:21.601   fromCPR loadAlert id = 0 message = ~~~~~1789371201559 DATASERVER=OK
14-09:33:21.700   Start dataserver: result string is: DATASERVER=OK
14-09:33:21.700   Start dataserver: getting current address
14-09:33:21.704   Start dataserver: URL=corbaloc:iiop:132.229.121.209:5505/NMRDataServer, IOR=IOR:010000002800000049444c3a6272756b65722e64652f646174612f4469736346756e6350726f76696465723a312e300001000000000000009800000001010200100000003133322e3232392e3132312e3230390081150000110000004e4d5244617461536572766572312e31300000000400000000000000080000000100000000545441010000001c000000010000000100010001000000010001050901010001000000090101000300000014000000010000000a0000003132372e302e302e310081151400000008000000010066006600b315
14-09:33:21.704   Start dataserver: local contact, using URL: corbaloc:iiop:127.0.0.1:5505/NMRDataServer
14-09:33:21.708   Start dataserver: path to logfile is "/opt/topspin3.7.0/prog/curdir/nmrafd/stdout.dataserver.34743"
14-09:33:21.708   Start dataserver: successful.
14-09:33:21.709   cmd=_start_browsers
14-09:33:21.781   cmd=_show_lic
14-09:33:21.783   getLicense called: attribute
14-09:33:21.784   to CPR: "checkout_license verbose attribute" [id 2], COMMAND_WAIT_TERM
14-09:33:21.785   external cmd from "CPR": "lic_attribute" on "", option="-2"
14-09:33:21.785   cmd=lic_attribute
14-09:33:21.786   CPR task 2 resumed normally, termstat=0
14-09:33:21.787   getAttribute called: 0
14-09:33:21.787   License type: 
14-09:33:21.787   wait for license attribute
14-09:33:21.787   get license attribute finished
14-09:33:21.787   cmd=_set_cprhist
14-09:33:21.865   cmd=configuretopspin
14-09:33:21.867   Installation configured with curinst: AVANCEIII600
14-09:33:21.972   LOCKCONF, SamChgEth[0-3]: [DLM, 149.236.99.55, no, no, no, tty04]
14-09:33:22.000   Datastation: false, ELCB: true, EVTU: false, BACSAIR: true, MAS/2: false, MAS Unit: false,
                    VTU: true, Prodigy: false, RCU: false, Multi-receiver: false, number of receivers: 1,
                    Multipeak lock: true, NMR thermometer: false, 19F lock: false, SampleChanger: true, SampleXpress over Ethernet: false,
                    Lock: true, SoftLock: false
14-09:33:22.002   Trying to start hardware access servers...
14-09:33:22.033   FidStoreServer0 successfully started
14-09:33:22.034   cmd=stopsoapprobeserver
14-09:33:22.034   cmd=stopsoapexternalsampletransportserver
14-09:33:22.034   cmd=stopsoapnotificationserver
14-09:33:22.035   cmd=startwatcher
14-09:33:22.035   cmd=connecttocryo
14-09:33:22.035   cmd=startbsmsserver
14-09:33:22.036   Start BsmsServer
14-09:33:22.042   Do not publish
14-09:33:22.064   cmd=login start
14-09:33:22.068   cmd=runonce
14-09:33:22.070   Option: null
14-09:33:22.070   cmd=license
14-09:33:22.072   License code: CheckExpireDate
14-09:33:22.072   to CPR: "checkout_license expire spect XWINNMR" [id 3], COMMAND_WAIT_TERM
14-09:33:22.152   Start BsmsServer: activation successful, id: corbaloc:iiop:132.229.121.209:5090/4346494571/CtrlPOA/BsmsServer
14-09:33:22.154   cmd=checkbsmsfirmwareversion
14-09:33:22.264   external cmd from "CPR": "lic_expire_date 2037-09-28T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:33:22.264   cmd=lic_expire_date 2037-09-28T17:14:36+02:00
14-09:33:22.265   CPR task 3 resumed normally, termstat=0
14-09:33:22.414   cmd=expinstallcheck
14-09:33:22.414   cmd=_db_cleanxml
14-09:33:22.423   cmd=startspooler
14-09:33:22.423   cmd=mons
14-09:33:22.435   cmd=monacb
14-09:33:22.453   cmd=montime
14-09:33:22.455   cmd=monspooler
14-09:33:22.458   cmd=monbsmsstatus
14-09:33:22.461   cmd=montemp
14-09:33:22.463   cmd=monbsms
14-09:33:22.466   cmd=monbsmstemp
14-09:33:22.468   cmd=monpowindicator
14-09:33:22.468   cmd=monlock
14-09:33:22.469   cmd=monacq
14-09:33:22.486   to CPR: "checkout_license expire no_checkout 1011013000:TOPSPIN3" [id 4], COMMAND_WAIT_TERM
14-09:33:22.487   Cryo: Firmware "20190123" (00.02), XT support: true
14-09:33:22.616   Suitable firmware found: 230420
14-09:33:22.616   Initializing coil temperature values
14-09:33:22.633   cmd=montempRun
14-09:33:22.637   cmd=monacqrun
14-09:33:22.639   cmd=monlockRun
14-09:33:22.666   external cmd from "CPR": "lic_expire_date 2037-09-28T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:33:22.666   cmd=lic_expire_date 2037-09-28T17:14:36+02:00
14-09:33:22.667   CPR task 4 resumed normally, termstat=0
14-09:33:22.668   to CPR: "checkout_license expire no_checkout 1011140100:SMP_CMCA" [id 5], COMMAND_WAIT_TERM
14-09:33:22.686   cmd=stopbsmssoapserver
14-09:33:22.686   BasicSampleChanger arguments: topspinGen='ts3' ipAddress=149.236.99.1 logFileSize=104857600 restartServerIpAddress=132.229.121.209 restartServerPort=5521
14-09:33:22.691   cmd=monacbrun
14-09:33:22.692   Stopping BsmsSoapServer
14-09:33:22.831   Error getting target temperature value from VTU: Unable to get parameter VTC_PROBE_CH
14-09:33:22.848   external cmd from "CPR": "lic_expire_date 2023-09-07T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:33:22.848   cmd=lic_expire_date 2023-09-07T17:14:36+02:00
14-09:33:22.848   CPR task 5 resumed normally, termstat=0
14-09:33:22.849   to CPR: "checkout_license expire no_checkout 1011140200:SMP_CMCA_CLASSROOM" [id 6], COMMAND_WAIT_TERM
14-09:33:22.905   CPR task 6 resumed normally, termstat=-1
14-09:33:22.906   Error checking expire date for '1011140200:SMP_CMCA_CLASSROOM, -1
14-09:33:22.906   to CPR: "checkout_license expire no_checkout 1011160100:SMP_CMCQ" [id 7], COMMAND_WAIT_TERM
14-09:33:22.968   CPR task 7 resumed normally, termstat=-1
14-09:33:22.969   Error checking expire date for '1011160100:SMP_CMCQ, -1
14-09:33:22.969   to CPR: "checkout_license expire no_checkout 1021290200:FBS_ADVANCED" [id 8], COMMAND_WAIT_TERM
14-09:33:23.028   CPR task 8 resumed normally, termstat=-1
14-09:33:23.028   Error checking expire date for '1021290200:FBS_ADVANCED, -1
14-09:33:23.029   to CPR: "checkout_license expire no_checkout 1011330100:SMP_POTENCY_MR" [id 9], COMMAND_WAIT_TERM
14-09:33:23.091   CPR task 9 resumed normally, termstat=-1
14-09:33:23.091   Error checking expire date for '1011330100:SMP_POTENCY_MR, -1
14-09:33:23.092   to CPR: "checkout_license expire no_checkout 1011320100:SMP_SMART_DRIVE_NMR" [id 10], COMMAND_WAIT_TERM
14-09:33:23.151   CPR task 10 resumed normally, termstat=-1
14-09:33:23.152   Error checking expire date for '1011320100:SMP_SMART_DRIVE_NMR, -1
14-09:33:23.153   to CPR: "checkout_license expire no_checkout 1011150100:SMP_STRUCELU" [id 11], COMMAND_WAIT_TERM
14-09:33:23.343   external cmd from "CPR": "lic_expire_date 2023-09-07T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:33:23.343   cmd=lic_expire_date 2023-09-07T17:14:36+02:00
14-09:33:23.345   CPR task 11 resumed normally, termstat=0
14-09:33:23.345   to CPR: "checkout_license expire no_checkout 1011150200:SMP_STRUCELU_CLASSROOM" [id 12], COMMAND_WAIT_TERM
14-09:33:23.396   CPR task 12 resumed normally, termstat=-1
14-09:33:23.396   Error checking expire date for '1011150200:SMP_STRUCELU_CLASSROOM, -1
14-09:33:23.398   to CPR: "checkout_license expire no_checkout 1021030000:TOPSPIN_NUS" [id 13], COMMAND_WAIT_TERM
14-09:33:23.457   CPR task 13 resumed normally, termstat=-1
14-09:33:23.457   Error checking expire date for '1021030000:TOPSPIN_NUS, -1
14-09:33:23.458   to CPR: "checkout_license expire no_checkout 1021230100:ASSURE-SST" [id 14], COMMAND_WAIT_TERM
14-09:33:23.649   external cmd from "CPR": "lic_expire_date 2023-09-07T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:33:23.649   cmd=lic_expire_date 2023-09-07T17:14:36+02:00
14-09:33:23.650   CPR task 14 resumed normally, termstat=0
14-09:33:23.652   to CPR: "checkout_license expire no_checkout 1021230200:AUTO_CALIBRATE" [id 15], COMMAND_WAIT_TERM
14-09:33:23.702   CPR task 15 resumed normally, termstat=-1
14-09:33:23.702   Error checking expire date for '1021230200:AUTO_CALIBRATE, -1
14-09:33:29.044   cmd=_create_drag_tranfer_handler
14-09:33:29.057   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-09:33:29.096   cmd[0]=browse_update_last50
14-09:33:29.098   cmd[0]=add_datadir_automatic
14-09:33:29.120   cmd[0]=_create_drag_tranfer_handler
14-09:33:29.124   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:33:29.124   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-09:33:29.457   Address used for BasicSampleChanger: 149.236.99.1:9999, changer address: 149.236.99.55
14-09:33:32.713   cmd[0]=lockdisp
14-09:33:32.721   cmd[0]=_create_drag_tranfer_handler
14-09:33:32.759   cmd[0]=_create_drag_tranfer_handler
14-09:33:32.762   Entered MODULE de.bruker.nmr.sc.lockdisp.impl.Module
14-09:33:32.762   adddataset 132.229.121.209
14-09:33:38.833   cmd[0]=iir
14-09:33:38.842   cmd[0]=xmac iir 
14-09:33:38.852   cmd[0]=_show_stat
14-09:33:40.186   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-09:33:40.223   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-09:33:52.070   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-09:33:52.080   to CPR: "resetgab" [id 16], COMMAND_WAIT_TERM
14-09:33:53.048   CPR task 16 resumed normally, termstat=0
14-09:33:54.800   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-09:34:08.320   cmd[0]=about
14-09:34:08.415   cmd[0]=statusparsmodified
14-09:34:08.416   cmd[0]=statparsmodifiedworker
14-09:34:08.513   cmd[0]=acqustop end POWCHK_ON ACQU
14-09:34:08.514   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-09:34:11.416   command ii terminated
14-09:34:11.418   cmd[0]=_show_stat
14-09:34:13.601   external cmd from "CPR": "blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad44d312@@deviceState@@device code:0x60000001: Device is up
" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:34:13.601   cmd[0]=blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad44d312@@deviceState@@device code:0x60000001: Device is up
14-09:34:13.602   sendIrptCommand blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad44d312@@deviceState@@device code:0x60000001: Device is up
14-09:34:13.603   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-09:34:14.143   external cmd from "CPR": "blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad44d3120001@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:34:14.143   cmd[0]=blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad44d3120001@@status@@127
14-09:34:14.145   sendIrptCommand blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad44d3120001@@status@@127
14-09:34:14.145   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-09:34:14.244   external cmd from "CPR": "blac_event @@0@@BLACHService2@@bruker-com-BLA-0000ad44d3120002@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:34:14.244   cmd[0]=blac_event @@0@@BLACHService2@@bruker-com-BLA-0000ad44d3120002@@status@@127
14-09:34:14.245   sendIrptCommand blac_event @@0@@BLACHService2@@bruker-com-BLA-0000ad44d3120002@@status@@127
14-09:34:14.246   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-09:34:14.344   external cmd from "CPR": "blac_event @@0@@BLACHService3@@bruker-com-BLA-0000ad44d3120003@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:34:14.344   cmd[0]=blac_event @@0@@BLACHService3@@bruker-com-BLA-0000ad44d3120003@@status@@127
14-09:34:14.346   sendIrptCommand blac_event @@0@@BLACHService3@@bruker-com-BLA-0000ad44d3120003@@status@@127
14-09:34:14.346   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-09:34:17.102   external cmd from "CPR": "blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad4c4d12@@deviceState@@device code:0x60000001: Device is up
" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:34:17.103   cmd[0]=blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad4c4d12@@deviceState@@device code:0x60000001: Device is up
14-09:34:17.104   sendIrptCommand blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad4c4d12@@deviceState@@device code:0x60000001: Device is up
14-09:34:17.105   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-09:34:17.203   external cmd from "CPR": "blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad4c4d120001@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-09:34:17.204   cmd[0]=blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad4c4d120001@@status@@127
14-09:34:17.205   sendIrptCommand blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad4c4d120001@@status@@127
14-09:34:17.205   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-09:34:47.588   cmd[0]=showconf
14-09:36:07.736   cmd[0]=edhead
14-09:36:37.540   Password request : The password entered is incorrect.
14-09:37:24.393   allDatasetsMap entry removed, family: PARAM_PROBE_PROPERTY, null : PZ168568_0015
14-09:38:12.507   allDatasetsMap entry removed, family: PARAM_PROBE_PROPERTY, null : PZ44896_0064
14-09:38:20.672   cmd[0]=ha
14-09:38:24.008   HA: 7 addresses found
14-09:38:24.017   149.236.99.254 COOLING UNIT/5 Z159073/049 ECL 01.00 (UnitTypeCryoCool)
14-09:38:24.018   149.236.99.253 BLAXH2H300/100/150 E 200-600MHZ W1345096/0199 ECL 1 (UnitTypeBla)
14-09:38:24.018   149.236.99.252 BLAX300 E 6-365MHZ INR W1345092/0382 ECL 1 (UnitTypeBla)
14-09:38:24.018   149.236.99.90 IPSO (UnitTypeIpso)
14-09:38:24.018   149.236.99.20 BSMS/2 ELCB Z100818/02471 ECL 06.02 (UnitTypeBsms)
14-09:38:24.019   149.236.99.89 AQS DRU-E Z102520/01974 ECL 06.01 (UnitTypeDru)
14-09:38:24.019   149.236.99.55 SampleJet (UnitTypeSamChg)
14-09:38:48.136   cmd[0]=ha
14-09:38:51.384   HA: 7 addresses found
14-09:38:51.388   149.236.99.254 COOLING UNIT/5 Z159073/049 ECL 01.00 (UnitTypeCryoCool)
14-09:38:51.388   149.236.99.253 BLAXH2H300/100/150 E 200-600MHZ W1345096/0199 ECL 1 (UnitTypeBla)
14-09:38:51.388   149.236.99.252 BLAX300 E 6-365MHZ INR W1345092/0382 ECL 1 (UnitTypeBla)
14-09:38:51.388   149.236.99.90 IPSO (UnitTypeIpso)
14-09:38:51.389   149.236.99.20 BSMS/2 ELCB Z100818/02471 ECL 06.02 (UnitTypeBsms)
14-09:38:51.389   149.236.99.89 AQS DRU-E Z102520/01974 ECL 06.01 (UnitTypeDru)
14-09:38:51.389   149.236.99.55 SampleJet (UnitTypeSamChg)
14-09:39:53.912   cmd[0]=setshim
14-09:40:18.304   cmd[0]=atmaserver
14-09:40:18.312   cmd[0]=_checkcmd atmaserver
14-09:40:18.313   cmd[0]=_launchwithorwithoutdata _checkcmd atmaserver
14-09:40:18.322   external cmd from "CPR": "_checkcmd `CPR_ILLEGAL_COMMAND`
atmaserver" on "", option="-2"
14-09:40:18.322   cmd[0]=_checkcmd `CPR_ILLEGAL_COMMAND`
atmaserver
14-09:40:18.325   Command not implemented
atmaserver
14-09:40:25.296   cmd[0]=atmainfo
14-09:40:25.344   Connect to Atmaserver: local contact, using: corbaloc:iiop:127.0.0.1:5522/AtmaServer
14-09:40:25.352   start Atmaserver: got object successfully.
14-09:40:26.368   fromCPR loadAlert id = 0 message = ILLEGAL TERMINATION
of process 36039
in state EXEC
due to signal 6 (Aborted)
14-09:40:26.370   atmaserver : ILLEGAL TERMINATION
of process 36039
in state EXEC
due to signal 6 (Aborted)
14-09:40:41.687   cmd[0]=edte
14-09:40:41.688   cmd[0]=edteold 
14-09:40:42.302   cmd[0]=_create_drag_tranfer_handler
14-09:43:05.650   cmd[0]=new
14-09:43:11.897   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/group092026Name/1/pdata/1
14-09:43:11.918   cmd[0]=browse_update_last50
14-09:43:11.919   cmd[0]=add_datadir_automatic
14-09:43:11.925   cmd[0]=_create_drag_tranfer_handler
14-09:43:11.927   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:43:11.927   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/group092026Name/1/pdata/1
14-09:43:16.207   cmd[0]=edc
14-09:43:16.208   cmd[0]=new
14-09:44:02.415   cmd[0]=iconc
14-09:44:09.044   external cmd from "CPR": "-exch exchange.36120 feedback portfolio" on "/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1", option="-3"
14-09:44:09.045   cmd[0]=feedback portfolio __WAITSTART__w__WAITEND__
14-09:44:09.161   confirm external command termination: "-3"
14-09:45:06.124   external cmd from "CPR": "-exch exchange.36120 _getprobeinfo" on "/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1", option="-3"
14-09:45:06.125   cmd[0]=_getprobeinfo __WAITSTART__w__WAITEND__
14-09:45:06.140   confirm external command termination: "-3"
14-09:45:06.162   external cmd from "CPR": "-exch exchange.36120 _getedheadids" on "/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1", option="-3"
14-09:45:06.162   cmd[0]=_getedheadids __WAITSTART__w__WAITEND__
14-09:45:06.166   confirm external command termination: "-3"
14-09:45:06.168   external cmd from "CPR": "-exch exchange.36120 _getedheadids" on "/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1", option="-3"
14-09:45:06.168   cmd[0]=_getedheadids __WAITSTART__w__WAITEND__
14-09:45:06.173   confirm external command termination: "-3"
14-09:53:32.302   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/18/pdata/1
14-09:53:32.324   cmd[0]=browse_update_last50
14-09:53:32.325   cmd[0]=add_datadir_automatic
14-09:53:32.328   cmd[0]=_create_drag_tranfer_handler
14-09:53:32.330   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:53:32.331   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/18/pdata/1
14-09:53:34.598   cmd[0]=efp
14-09:53:34.604   cmd[0]=isNmr
14-09:53:34.744   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/18/pdata/1
14-09:53:34.796   cmd[0]=browse_update_last50
14-09:53:34.797   cmd[0]=add_datadir_automatic
14-09:53:36.262   cmd[0]=ap
14-09:53:36.269   cmd[0]=xmac ap 
14-09:53:36.273   cmd[0]=_show_stat
14-09:53:36.303   cmd[0]=apks __WAITSTART__w__WAITEND__
14-09:53:36.304   cmd[0]=isNmr
14-09:53:36.306   to CPR: "apks" [id 17], COMMAND_WAIT_TERM
14-09:53:36.375   CPR task 17 resumed normally, termstat=0
14-09:53:36.377   cmd[0]=_show_stat
14-09:53:36.383   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/18/pdata/1
14-09:53:36.419   cmd[0]=browse_update_last50
14-09:53:36.421   cmd[0]=add_datadir_automatic
14-09:53:37.086   cmd[0]=*2
14-09:53:37.414   cmd[0]=*2
14-09:53:37.694   cmd[0]=*2
14-09:53:38.566   cmd[0]=*2
14-09:54:00.176   cmd[0]=.all
14-09:54:17.997   cmd[0]=.all
14-09:54:26.328   cmd[0]=.all
14-09:54:34.242   cmd[0]=.all
14-09:54:37.093   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:37.110   cmd[0]=browse_update_last50
14-09:54:37.111   cmd[0]=add_datadir_automatic
14-09:54:37.113   cmd[0]=_create_drag_tranfer_handler
14-09:54:37.116   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:54:37.117   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:40.182   cmd[0]=xfb
14-09:54:40.183   cmd[0]=isNmr
14-09:54:40.837   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:40.923   cmd[0]=browse_update_last50
14-09:54:40.924   cmd[0]=add_datadir_automatic
14-09:54:48.950   cmd[0]=sr
14-09:54:50.647   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:50.708   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:50.784   allDatasetsMap entry removed, family: PARAM_PROCESSING, 000028 : /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:56.750   cmd[0]=lev32
14-09:54:56.757   cmd[0]=xmac lev32 
14-09:54:56.762   cmd[0]=_show_stat
14-09:54:56.789   cmd[0]=rpar level32 clevels __WAITSTART__w__WAITEND__
14-09:54:56.804   cmd[0]=_exec_cpr_cmd
14-09:54:56.805   to CPR: "rpar "/opt/topspin3.7.0/exp/stan/nmr/par/user/level32" clevels" [id 18], COMMAND_WAIT_TERM
14-09:54:56.817   CPR task 18 resumed normally, termstat=0
14-09:54:56.827   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/15/pdata/1
14-09:54:56.887   cmd[0]=browse_update_last50
14-09:54:56.889   cmd[0]=add_datadir_automatic
14-09:54:57.512   cmd[0]=_show_stat
14-09:54:58.190   cmd[0]=/2
14-09:54:58.334   cmd[0]=/2
14-09:54:58.470   cmd[0]=/2
14-09:54:59.974   cmd[0]=.ls
14-09:55:02.824   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:02.845   cmd[0]=browse_update_last50
14-09:55:02.846   cmd[0]=add_datadir_automatic
14-09:55:02.847   cmd[0]=_create_drag_tranfer_handler
14-09:55:02.851   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:55:02.852   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:07.014   cmd[0]=xfb
14-09:55:07.015   cmd[0]=isNmr
14-09:55:07.662   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:07.715   cmd[0]=browse_update_last50
14-09:55:07.716   cmd[0]=add_datadir_automatic
14-09:55:13.638   cmd[0]=sr
14-09:55:15.498   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:15.556   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:15.606   allDatasetsMap entry removed, family: PARAM_PROCESSING, 000050 : /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:27.086   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z/4/pdata/1
14-09:55:27.102   cmd[0]=browse_update_last50
14-09:55:27.103   cmd[0]=add_datadir_automatic
14-09:55:27.105   cmd[0]=_create_drag_tranfer_handler
14-09:55:27.107   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:55:27.108   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z/4/pdata/1
14-09:55:29.654   cmd[0]=c
14-09:55:29.660   cmd[0]=xmac c 
14-09:55:29.665   cmd[0]=_show_stat
14-09:55:29.693   cmd[0]=rpar c-* __WAITSTART__w__WAITEND__
14-09:55:30.090   cmd[0]=_show_stat
14-09:55:35.337   cmd[0]=rpar "/opt/topspin3.7.0/exp/stan/nmr/par/user/c-hmbcNEW"
14-09:55:36.507   cmd[0]=_exec_cpr_cmd
14-09:55:36.510   to CPR: "rpar "/opt/topspin3.7.0/exp/stan/nmr/par/user/c-hmbcNEW" acqu proc outd title level" [id 19], COMMAND_WAIT_TERM
14-09:55:36.530   CPR task 19 resumed normally, termstat=0
14-09:55:36.536   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z/4/pdata/1
14-09:55:36.549   cmd[0]=browse_update_last50
14-09:55:36.552   cmd[0]=add_datadir_automatic
14-09:55:39.206   cmd[0]=sr
14-09:55:54.047   allDatasetsMap entry removed, family: PARAM_PROCESSING, 000065 : /opt/nmrdata/data/nmrafd/nmr/z/4/pdata/1
14-09:55:56.398   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:56.412   cmd[0]=browse_update_last50
14-09:55:56.413   cmd[0]=add_datadir_automatic
14-09:55:56.414   cmd[0]=_create_drag_tranfer_handler
14-09:55:56.416   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:55:56.417   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/16/pdata/1
14-09:55:58.702   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/10/pdata/1
14-09:55:58.717   cmd[0]=browse_update_last50
14-09:55:58.718   cmd[0]=add_datadir_automatic
14-09:55:58.719   cmd[0]=_create_drag_tranfer_handler
14-09:55:58.722   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:55:58.722   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/10/pdata/1
14-09:56:01.998   cmd[0]=xfb
14-09:56:01.999   cmd[0]=isNmr
14-09:56:02.635   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/10/pdata/1
14-09:56:02.685   cmd[0]=browse_update_last50
14-09:56:02.686   cmd[0]=add_datadir_automatic
14-09:56:05.974   cmd[0]=sr
14-09:56:12.870   allDatasetsMap entry removed, family: PARAM_PROCESSING, 000079 : /opt/nmrdata/data/nmrafd/nmr/Molphys092026Ioana/10/pdata/1
14-09:56:39.270   cmd[0]=re z 1
14-09:56:39.285   cmd[0]=browse_update_last50
14-09:56:39.286   cmd[0]=add_datadir_automatic
14-09:56:39.287   cmd[0]=_create_drag_tranfer_handler
14-09:56:39.289   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-09:56:39.291   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:11:59.028   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/group092026Name/1/pdata/1
14-10:11:59.045   cmd[0]=browse_update_last50
14-10:11:59.046   cmd[0]=add_datadir_automatic
14-10:11:59.049   cmd[0]=_create_drag_tranfer_handler
14-10:11:59.054   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:11:59.055   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/group092026Name/1/pdata/1
14-10:12:00.892   cmd[0]=new
14-10:12:44.836   cmd[0]=_exec_cpr_cmd
14-10:12:44.839   to CPR: "edcau "/opt/nmrdata/data/nmrafd/nmr" "z_atma_check_092026" 1 1" [id 20], COMMAND_WAIT_TERM
14-10:12:44.861   CPR task 20 resumed normally, termstat=0
14-10:12:44.861   cmd[0]=_newfinish
14-10:12:44.871   cmd[0]=dispdata
14-10:12:44.878   cmd[0]=browse_update_last50
14-10:12:44.879   cmd[0]=add_datadir_automatic
14-10:12:44.879   cmd[0]=_create_drag_tranfer_handler
14-10:12:44.884   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:12:44.884   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:12:49.100   cmd[0]=lk
14-10:12:49.108   cmd[0]=xmac lk 
14-10:12:49.114   cmd[0]=_show_stat
14-10:12:49.141   cmd[0]=lock __WAITSTART__w__WAITEND__
14-10:12:53.147   cmd[0]=_show_stat
14-10:13:48.933   cmd[0]=w
14-10:13:48.939   cmd[0]=xmac w 
14-10:13:48.944   cmd[0]=_show_stat
14-10:13:48.968   cmd[0]=qu atma coarse __WAITSTART__w__WAITEND__
14-10:13:48.972   [AbstractQueueJob] Executing job
14-10:13:48.972   cmd[0]=atma coarse __WAITSTART__w__WAITEND__
14-10:13:48.973   cmd[0]=isNmr
14-10:13:48.981   cmd[0]=_show_stat
14-10:13:49.007   to CPR: "atma coarse" [id 21], COMMAND_WAIT_TERM
14-10:13:49.083   CPR task 21 resumed normally, termstat=-1
14-10:13:49.084   Priority job 'PriorityQueueJob[id=1, command=atma coarse, priority=0, dataObject=/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1]' returned error code -1 for command 'atma coarse'.
14-10:14:01.524   cmd[0]=w
14-10:14:01.530   cmd[0]=xmac w 
14-10:14:01.534   cmd[0]=_show_stat
14-10:14:01.569   cmd[0]=qu atma coarse __WAITSTART__w__WAITEND__
14-10:14:01.570   [AbstractQueueJob] Executing job
14-10:14:01.571   cmd[0]=atma coarse __WAITSTART__w__WAITEND__
14-10:14:01.572   cmd[0]=isNmr
14-10:14:01.573   cmd[0]=_show_stat
14-10:14:01.606   to CPR: "atma coarse" [id 22], COMMAND_WAIT_TERM
14-10:14:12.700   external cmd from "CPR": "-exch exchange.37473 wobbrout" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-6"
14-10:14:12.701   cmd[0]=wobbrout __WAITSTART__w__WAITEND__
14-10:14:12.756   sendCommand wobbrout  on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:14:13.028   confirm external command termination: "-6"
14-10:14:13.028   command wobbrout terminated
14-10:14:16.097   cmd[0]=browse_update_last50
14-10:14:16.098   cmd[0]=add_datadir_automatic
14-10:14:16.098   external cmd from "CPR": "wobb noLED f1  var 256 9.000000" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:16.099   cmd[0]=wobb noLED f1  var 256 9.000000
14-10:14:16.099   cmd[0]=browse_update_last50
14-10:14:16.100   cmd[0]=add_datadir_automatic
14-10:14:16.105   added to parameterCacheList: 0
14-10:14:16.105   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:14:16.105   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:14:16.105   parameterCacheFactoryMap cleared
14-10:14:16.109   sendCommand wobb noLED f1  var 256 9.000000 tsUser=null on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:14:24.406   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:14:29.740   cmd[0]=acqustart WOBB POWCHK_ON 1
14-10:14:29.741   processEvent, got command from go: WOBB
14-10:14:29.742   added to parameterCacheList: 0
14-10:14:29.742   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:14:31.244   cmd[0]=_create_drag_tranfer_handler
14-10:14:31.259   Entered MODULE de.bruker.nmr.sc.acq.impl.Module
14-10:14:31.259   adddataset corbaloc:iiop:127.0.0.1:5506/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:14:32.411   external cmd from "CPR": "halt" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-6"
14-10:14:32.411   cmd[0]=halt __WAITSTART__w__WAITEND__
14-10:14:32.411   sendIrptCommand halt 
14-10:14:32.413   interrupt sent successfully
14-10:14:32.413   confirm external command termination: "-6"
14-10:14:32.420   cmd[0]=statusparsmodified
14-10:14:33.119   cmd[0]=acqustop end POWCHK_ON WOBB
14-10:14:33.120   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:14:33.120   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:14:33.126   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:14:36.142   command wobb terminated on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:14:36.416   external cmd from "CPR": "halt" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-6"
14-10:14:36.416   cmd[0]=halt __WAITSTART__w__WAITEND__
14-10:14:36.418   sendIrptCommand halt 
14-10:14:36.418   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:14:36.418   confirm external command termination: "-6"
14-10:14:36.423   cmd[0]=browse_update_last50
14-10:14:36.425   cmd[0]=add_datadir_automatic
14-10:14:36.427   cmd[0]=browse_update_last50
14-10:14:36.428   cmd[0]=add_datadir_automatic
14-10:14:37.624   CPR task 22 resumed normally, termstat=0
14-10:14:40.836   cmd[0]=tscc
14-10:14:40.850   cmd[0]=xmac tscc 
14-10:14:40.854   cmd[0]=_show_stat
14-10:14:40.881   cmd[0]=qu topshim ordmax=5 convcomp __WAITSTART__w__WAITEND__
14-10:14:40.883   [AbstractQueueJob] Executing job
14-10:14:40.883   cmd[0]=topshim ordmax=5 convcomp __WAITSTART__w__WAITEND__
14-10:14:40.885   cmd[0]=_show_stat
14-10:14:40.886   to CPR: "topshim ordmax=5 convcomp" [id 23], COMMAND_WAIT_TERM
14-10:14:40.920   external cmd from "CPR": "topshim_status 1" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:40.920   cmd[0]=topshim_status 1
14-10:14:40.922   external cmd from "CPR": "topshim_text 2 initialisation" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:40.922   cmd[0]=topshim_text 2 initialisation
14-10:14:41.019   external cmd from "CPR": "topshim_text 1 solvent = MeOD" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:41.019   cmd[0]=topshim_text 1 solvent = MeOD
14-10:14:41.791   external cmd from "CPR": "topshim_text 3 delete old data" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:41.791   cmd[0]=topshim_text 3 delete old data
14-10:14:41.793   external cmd from "CPR": "topshim_text 3 get lock info" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:41.793   cmd[0]=topshim_text 3 get lock info
14-10:14:41.859   external cmd from "CPR": "topshim_report 
New TopShim run:

version = topshim 2.4.0
date = 2026-09-14
time = 10:14:41.858
command = topshim  ordmax=5 convcomp
" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.860   cmd[0]=topshim_report 
New TopShim run:

version = topshim 2.4.0
date = 2026-09-14
time = 10:14:41.858
command = topshim  ordmax=5 convcomp __WAITSTART__w__WAITEND__
14-10:14:41.861   confirm external command termination: "-7"
14-10:14:41.873   external cmd from "CPR": "topshim_report FINE SHIMMING" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.874   cmd[0]=topshim_report FINE SHIMMING __WAITSTART__w__WAITEND__
14-10:14:41.875   confirm external command termination: "-7"
14-10:14:41.876   external cmd from "CPR": "topshim_report 
Parameters:
" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.876   cmd[0]=topshim_report 
Parameters: __WAITSTART__w__WAITEND__
14-10:14:41.877   confirm external command termination: "-7"
14-10:14:41.878   external cmd from "CPR": "topshim_report probe = Z168568_0015" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.878   cmd[0]=topshim_report probe = Z168568_0015 __WAITSTART__w__WAITEND__
14-10:14:41.879   confirm external command termination: "-7"
14-10:14:41.880   external cmd from "CPR": "topshim_report solvent = MeOD" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.880   cmd[0]=topshim_report solvent = MeOD __WAITSTART__w__WAITEND__
14-10:14:41.881   confirm external command termination: "-7"
14-10:14:41.881   external cmd from "CPR": "topshim_report shim nucleus = 2H" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.882   cmd[0]=topshim_report shim nucleus = 2H __WAITSTART__w__WAITEND__
14-10:14:41.882   confirm external command termination: "-7"
14-10:14:41.894   external cmd from "CPR": "topshim_report nucleus optimised for = 1H" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.895   cmd[0]=topshim_report nucleus optimised for = 1H __WAITSTART__w__WAITEND__
14-10:14:41.896   confirm external command termination: "-7"
14-10:14:41.897   external cmd from "CPR": "topshim_report o1p (from lock) = 3.30 ppm" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.897   cmd[0]=topshim_report o1p (from lock) = 3.30 ppm __WAITSTART__w__WAITEND__
14-10:14:41.898   confirm external command termination: "-7"
14-10:14:41.899   external cmd from "CPR": "topshim_report selective excitation width = 1.0 ppm" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.899   cmd[0]=topshim_report selective excitation width = 1.0 ppm __WAITSTART__w__WAITEND__
14-10:14:41.900   confirm external command termination: "-7"
14-10:14:41.901   external cmd from "CPR": "topshim_report 
Results:
" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:41.901   cmd[0]=topshim_report 
Results: __WAITSTART__w__WAITEND__
14-10:14:41.902   confirm external command termination: "-7"
14-10:14:41.903   external cmd from "CPR": "topshim_text 3 coil setup" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:41.903   cmd[0]=topshim_text 3 coil setup
14-10:14:41.925   external cmd from "CPR": "topshim_text 3 gradient setup" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:41.925   cmd[0]=topshim_text 3 gradient setup
14-10:14:41.926   external cmd from "CPR": "topshim_text 3 shim system setup" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:41.926   cmd[0]=topshim_text 3 shim system setup
14-10:14:42.425   external cmd from "CPR": "topshim_text 3 prepare lock" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:42.425   cmd[0]=topshim_text 3 prepare lock
14-10:14:42.512   external cmd from "CPR": "topshim_text 3 switch lock off" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:42.512   cmd[0]=topshim_text 3 switch lock off
14-10:14:42.613   external cmd from "CPR": "topshim_text 3 lock out" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:14:42.613   cmd[0]=topshim_text 3 lock out
14-10:14:42.885   external cmd from "CPR": "disableautoacq" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-7"
14-10:14:42.885   cmd[0]=disableautoacq __WAITSTART__w__WAITEND__
14-10:14:42.886   confirm external command termination: "-7"
14-10:14:42.888   external cmd from "CPR": "topshim_text 3 experiment setup" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:42.888   cmd[0]=topshim_text 3 experiment setup
14-10:14:42.914   external cmd from "CPR": "-exch exchange.37569 wobbrout" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:14:42.914   cmd[0]=wobbrout __WAITSTART__w__WAITEND__
14-10:14:42.915   sendCommand wobbrout  on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:14:43.082   confirm external command termination: "-7"
14-10:14:43.082   command wobbrout terminated
14-10:14:43.096   external cmd from "CPR": "getprosol" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:14:43.096   cmd[0]=getprosol __WAITSTART__w__WAITEND__
14-10:14:43.133   Edprosol Program Version 3.000
 File Version: 4.000
14-10:14:43.235   Edprosol: in prosol  version  found : ProgramVersion =3.000    FileVersion    =4.000
14-10:14:43.268   File not found:
prosol/Z168568_0015/MeOD/2H_obs.4_16
prosol/Z168568_0015/generic/2H_obs.4_16
prosol/Z168568_0015/MeOD/2H_obs
Read file:
prosol/Z168568_0015/generic/2H_obs
14-10:14:43.501   getprosol finished!
14-10:14:43.508   allDatasetsMap entry removed, family: PARAM_ACQUISITION, 000100 : /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:14:43.508   confirm external command termination: "-7"
14-10:14:43.519   external cmd from "CPR": "-exch exchange.37569 wobbrout" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:14:43.519   cmd[0]=wobbrout __WAITSTART__w__WAITEND__
14-10:14:43.520   sendCommand wobbrout  on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:14:43.698   confirm external command termination: "-7"
14-10:14:43.698   command wobbrout terminated
14-10:14:43.718   external cmd from "CPR": "topshim_text 3 interpolate shim functions" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:43.718   cmd[0]=topshim_text 3 interpolate shim functions
14-10:14:43.720   external cmd from "CPR": "topshim_report 
1D Shimming:" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:14:43.721   cmd[0]=topshim_report 
1D Shimming: __WAITSTART__w__WAITEND__
14-10:14:43.721   confirm external command termination: "-7"
14-10:14:43.870   external cmd from "CPR": "noqu zg setcid=5 yes noBsmsAccess -noExpt" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:43.870   cmd[0]=noqu zg setcid=5 yes noBsmsAccess -noExpt
14-10:14:43.871   cmd[0]=zg setcid=5 yes noBsmsAccess -noExpt __WAITSTART__w__WAITEND__
14-10:14:43.871   external cmd from "CPR": "topshim_text 3 start acquisition" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:43.871   cmd[0]=topshim_text 3 start acquisition
14-10:14:43.872   cmd[0]=isNmr
14-10:14:43.904   added to parameterCacheList: 0
14-10:14:43.905   added to parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:14:43.905   removed from parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:14:43.905   parameterCacheFactoryMap cleared
14-10:14:43.905   sendCommand zg yes setcid=5  noBsmsAccess -noExpt tsUser=null on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:14:44.940   external cmd from "CPR": "topshim_text 3 acquire data" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:44.940   cmd[0]=topshim_text 3 acquire data
14-10:14:53.828   cmd[0]=acqustart ACQU POWCHK_ON
14-10:14:53.829   processEvent, got command from go: ACQU
14-10:14:53.830   added to parameterCacheList: 0
14-10:14:53.830   added to parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:14:55.425   cmd[0]=statusparsmodified
14-10:14:55.436   external cmd from "CPR": "topshim_text 3 2D Fourier transform" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:55.436   cmd[0]=topshim_text 3 2D Fourier transform
14-10:14:55.476   external cmd from "CPR": "noqu resume setcid=5" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:55.477   cmd[0]=noqu resume setcid=5
14-10:14:55.477   cmd[0]=resume setcid=5 __WAITSTART__w__WAITEND__
14-10:14:55.478   external cmd from "CPR": "topshim_text 3 acquire data" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:14:55.478   cmd[0]=topshim_text 3 acquire data
14-10:14:55.478   sendIrptCommand resume setcid=5
14-10:14:55.479   interrupt sent successfully
14-10:15:02.305   cmd[0]=statusparsmodified
14-10:15:02.316   external cmd from "CPR": "topshim_text 3 2D Fourier transform" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:02.316   cmd[0]=topshim_text 3 2D Fourier transform
14-10:15:02.343   external cmd from "CPR": "topshim_text 3 process field map" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:02.343   cmd[0]=topshim_text 3 process field map
14-10:15:02.344   external cmd from "CPR": "topshim_text 2 Evaluation of B0 map of iteration 1" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:02.344   cmd[0]=topshim_text 2 Evaluation of B0 map of iteration 1
14-10:15:03.830   external cmd from "CPR": "topshim_text 2 Equilibrating after setting shims" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:03.830   cmd[0]=topshim_text 2 Equilibrating after setting shims
14-10:15:04.756   external cmd from "CPR": "noqu resume setcid=5" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:04.757   cmd[0]=noqu resume setcid=5
14-10:15:04.757   cmd[0]=resume setcid=5 __WAITSTART__w__WAITEND__
14-10:15:04.757   external cmd from "CPR": "topshim_text 3 acquire data" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:04.758   cmd[0]=topshim_text 3 acquire data
14-10:15:04.758   sendIrptCommand resume setcid=5
14-10:15:04.759   interrupt sent successfully
14-10:15:11.582   cmd[0]=statusparsmodified
14-10:15:11.593   external cmd from "CPR": "topshim_text 3 2D Fourier transform" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:11.593   cmd[0]=topshim_text 3 2D Fourier transform
14-10:15:11.626   external cmd from "CPR": "topshim_text 3 process field map" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:11.626   cmd[0]=topshim_text 3 process field map
14-10:15:11.627   external cmd from "CPR": "topshim_report Iteration  1: FWHM Before= 0.2 Hz, Target= 0.2 Hz, After= 0.2 Hz" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:11.628   cmd[0]=topshim_report Iteration  1: FWHM Before= 0.2 Hz, Target= 0.2 Hz, After= 0.2 Hz __WAITSTART__w__WAITEND__
14-10:15:11.628   confirm external command termination: "-7"
14-10:15:11.630   external cmd from "CPR": "topshim_report Shim succeeded
" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:11.630   cmd[0]=topshim_report Shim succeeded __WAITSTART__w__WAITEND__
14-10:15:11.630   confirm external command termination: "-7"
14-10:15:11.632   external cmd from "CPR": "topshim_report Lineshape quality factor (1D spectrum, spinning) = 0.27
" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:11.633   cmd[0]=topshim_report Lineshape quality factor (1D spectrum, spinning) = 0.27 __WAITSTART__w__WAITEND__
14-10:15:11.633   confirm external command termination: "-7"
14-10:15:11.638   external cmd from "CPR": "topshim_text 2 finish" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:11.639   cmd[0]=topshim_text 2 finish
14-10:15:11.639   external cmd from "CPR": "stop acq_only" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:11.639   cmd[0]=stop acq_only __WAITSTART__w__WAITEND__
14-10:15:11.640   sendIrptCommand stop acq_only
14-10:15:11.641   interrupt sent successfully
14-10:15:11.645   Constructor explicitly set wobble state to: IDLE
14-10:15:11.645   confirm external command termination: "-7"
14-10:15:11.647   external cmd from "CPR": "topshim_text 3 stop acquisition" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:11.647   cmd[0]=topshim_text 3 stop acquisition
14-10:15:11.656   cmd[0]=statusparsmodified
14-10:15:12.350   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:15:12.351   processStop called: dataset from ct: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1, dataset from GO: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:15:12.351   removed from parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:15:12.360   command zg terminated on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:15:13.891   external cmd from "CPR": "topshim_text 3 lock status" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:13.891   cmd[0]=topshim_text 3 lock status
14-10:15:22.273   external cmd from "CPR": "enableautoacq" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:22.273   cmd[0]=enableautoacq __WAITSTART__w__WAITEND__
14-10:15:22.274   confirm external command termination: "-7"
14-10:15:22.277   external cmd from "CPR": "topshim_report shim changes: 
Z	+12
Z2	+8
Z3	+6
Z4	-42
Z5	-6" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:22.278   cmd[0]=topshim_report shim changes: 
Z	+12
Z2	+8
Z3	+6
Z4	-42
Z5	-6 __WAITSTART__w__WAITEND__
14-10:15:22.278   confirm external command termination: "-7"
14-10:15:22.281   external cmd from "CPR": "topshim_report 
duration = 42 sec" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:22.281   cmd[0]=topshim_report 
duration = 42 sec __WAITSTART__w__WAITEND__
14-10:15:22.282   confirm external command termination: "-7"
14-10:15:22.284   external cmd from "CPR": "topshim_report completed successfully" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:22.284   cmd[0]=topshim_report completed successfully __WAITSTART__w__WAITEND__
14-10:15:22.284   confirm external command termination: "-7"
14-10:15:22.286   external cmd from "CPR": "topshim_report finished Mon Sep 14 10:15:22 2026" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-7"
14-10:15:22.286   cmd[0]=topshim_report finished Mon Sep 14 10:15:22 2026 __WAITSTART__w__WAITEND__
14-10:15:22.287   confirm external command termination: "-7"
14-10:15:22.289   external cmd from "CPR": "topshim_status 0" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:15:22.289   cmd[0]=topshim_status 0
14-10:15:22.290   CPR task 23 resumed normally, termstat=0
14-10:15:23.099   to CPR: "wrpa "1D_acquisition_data"  2698  1  "/opt/nmrdata/backupnmrdata/data/nmrafd/nmr" y" [id 24], COMMAND_WAIT_TERM
14-10:15:23.108   fromCPR loadAlert id = 8 message = /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
No such file or directory
14-10:15:23.114   wrpa : /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
No such file or directory
14-10:15:26.249   toCPR sendAckn id=8 value=0
14-10:15:26.252   CPR task 24 resumed normally, termstat=-1
14-10:15:31.995   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:15:32.004   cmd[0]=browse_update_last50
14-10:15:32.004   cmd[0]=add_datadir_automatic
14-10:15:32.007   cmd[0]=_create_drag_tranfer_handler
14-10:15:32.009   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:15:32.010   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:15:33.859   cmd[0]=ns
14-10:15:34.851   allDatasetsMap entry removed, family: PARAM_ACQUISITION, 003499 : /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:15:36.836   cmd[0]=rz
14-10:15:36.842   cmd[0]=xmac rz 
14-10:15:36.846   cmd[0]=_show_stat
14-10:15:36.869   cmd[0]=qu rga __WAITSTART__w__WAITEND__
14-10:15:36.871   [AbstractQueueJob] Executing job
14-10:15:36.871   cmd[0]=rga __WAITSTART__w__WAITEND__
14-10:15:36.872   cmd[0]=isNmr
14-10:15:36.873   cmd[0]=qu zg __WAITSTART__w__WAITEND__
14-10:15:36.880   cmd[0]=_show_stat
14-10:15:36.894   cmd[0]=expt nowin "corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1"
14-10:15:36.894   sendCommand expt nowin "corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1" on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:15:36.904   added to parameterCacheList: 0
14-10:15:36.904   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:15:36.904   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:15:36.904   parameterCacheFactoryMap cleared
14-10:15:36.904   sendCommand rga  on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:15:36.920   cmd[0]=expt_setdata experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1" __WAITSTART__w__WAITEND__
14-10:15:36.922   [ExperimentTime::setData] Processing command thread request to set data 'experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1"'.
14-10:15:36.925   command expt terminated
14-10:15:40.255   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:15:50.491   cmd[0]=acqurga 40.3
14-10:15:53.666   command rga terminated
14-10:15:53.702   [AbstractQueueJob] Executing job
14-10:15:53.703   cmd[0]=zg yes __WAITSTART__w__WAITEND__
14-10:15:53.704   cmd[0]=isNmr
14-10:15:53.733   added to parameterCacheList: 0
14-10:15:53.733   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:15:53.733   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:15:53.733   parameterCacheFactoryMap cleared
14-10:15:53.733   sendCommand zg yes  tsUser=null on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:15:54.588   cmd[0]=acqustart ACQU POWCHK_ON
14-10:15:54.589   processEvent, got command from go: ACQU
14-10:15:54.590   added to parameterCacheList: 0
14-10:15:54.590   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:15:54.604   cmd[0]=_create_drag_tranfer_handler
14-10:15:54.632   Entered MODULE de.bruker.nmr.sc.acq.impl.Module
14-10:15:54.632   adddataset corbaloc:iiop:127.0.0.1:5506/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:00.091   cmd[0]=checklockshift __WAITSTART__w__WAITEND__
14-10:16:00.092   to CPR: "checklockshift" [id 25], COMMAND_WAIT_TERM
14-10:16:00.595   cmd[0]=browse_update_last50
14-10:16:00.597   cmd[0]=add_datadir_automatic
14-10:16:00.777   CPR task 25 resumed normally, termstat=0
14-10:16:00.778   cmd[0]=teget nostatus
14-10:16:00.782   cmd[0]=statusparsmodified
14-10:16:00.783   cmd[0]=statusparsmodified
14-10:16:00.829   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:16:00.830   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:00.830   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:16:00.838   command zg terminated on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:00.840   to CPR: "wrpa "z_atma_check_092026"  1  1  "/opt/nmrdata/backupnmrdata/data/nmrafd/nmr" y" [id 26], COMMAND_WAIT_TERM
14-10:16:00.847   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:00.867   adddataset corbaloc:iiop:127.0.0.1:5506/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:00.870   CPR task 26 resumed normally, termstat=0
14-10:16:01.217   cmd[0]=browse_update_last50
14-10:16:01.218   cmd[0]=add_datadir_automatic
14-10:16:40.147   cmd[0]=efp
14-10:16:40.148   cmd[0]=isNmr
14-10:16:40.198   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:40.229   cmd[0]=browse_update_last50
14-10:16:40.230   cmd[0]=add_datadir_automatic
14-10:16:40.907   cmd[0]=ap
14-10:16:40.914   cmd[0]=xmac ap 
14-10:16:40.918   cmd[0]=_show_stat
14-10:16:40.942   cmd[0]=apks __WAITSTART__w__WAITEND__
14-10:16:40.942   cmd[0]=isNmr
14-10:16:40.944   to CPR: "apks" [id 27], COMMAND_WAIT_TERM
14-10:16:40.969   CPR task 27 resumed normally, termstat=0
14-10:16:40.969   cmd[0]=_show_stat
14-10:16:40.974   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:16:40.998   cmd[0]=browse_update_last50
14-10:16:40.999   cmd[0]=add_datadir_automatic
14-10:16:43.044   cmd[0]=.sud
14-10:16:55.700   cmd[0]=.ph
14-10:16:55.700   cmd[0]=isNmr
14-10:16:55.703   cmd[0]=spec
14-10:16:55.703   cmd[0]=isNmr
14-10:16:55.705   cmd[0]=.real
14-10:16:55.778   Entered MODULE bruker.bio.topspin.procphase.phas1d.Module
14-10:16:56.796   cmd[0]=.ph0
14-10:17:01.060   cmd[0]=.sret
14-10:17:01.076   Finished MODULE bruker.bio.topspin.procphase.phas1d.Module
14-10:17:01.097   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:17:01.132   cmd[0]=browse_update_last50
14-10:17:01.134   cmd[0]=add_datadir_automatic
14-10:17:10.620   cmd[0]=.all
14-10:18:10.323   cmd[0]=edhead
14-10:19:10.131   cmd[0]=tscc
14-10:19:10.138   cmd[0]=xmac tscc 
14-10:19:10.143   cmd[0]=_show_stat
14-10:19:10.163   cmd[0]=qu topshim ordmax=5 convcomp __WAITSTART__w__WAITEND__
14-10:19:10.164   [AbstractQueueJob] Executing job
14-10:19:10.165   cmd[0]=topshim ordmax=5 convcomp __WAITSTART__w__WAITEND__
14-10:19:10.166   to CPR: "topshim ordmax=5 convcomp" [id 28], COMMAND_WAIT_TERM
14-10:19:10.166   cmd[0]=_show_stat
14-10:19:10.205   external cmd from "CPR": "topshim_status 1" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:10.206   cmd[0]=topshim_status 1
14-10:19:10.206   external cmd from "CPR": "topshim_text 2 initialisation" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:10.206   cmd[0]=topshim_text 2 initialisation
14-10:19:10.284   external cmd from "CPR": "topshim_text 1 solvent = MeOD" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:10.284   cmd[0]=topshim_text 1 solvent = MeOD
14-10:19:11.039   external cmd from "CPR": "topshim_text 3 delete old data" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.040   cmd[0]=topshim_text 3 delete old data
14-10:19:11.040   external cmd from "CPR": "topshim_text 3 get lock info" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.040   cmd[0]=topshim_text 3 get lock info
14-10:19:11.107   external cmd from "CPR": "topshim_report 
New TopShim run:

version = topshim 2.4.0
date = 2026-09-14
time = 10:19:11.106
command = topshim  ordmax=5 convcomp
" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.107   cmd[0]=topshim_report 
New TopShim run:

version = topshim 2.4.0
date = 2026-09-14
time = 10:19:11.106
command = topshim  ordmax=5 convcomp __WAITSTART__w__WAITEND__
14-10:19:11.107   confirm external command termination: "-10"
14-10:19:11.120   external cmd from "CPR": "topshim_report FINE SHIMMING" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.120   cmd[0]=topshim_report FINE SHIMMING __WAITSTART__w__WAITEND__
14-10:19:11.120   confirm external command termination: "-10"
14-10:19:11.121   external cmd from "CPR": "topshim_report 
Parameters:
" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.121   cmd[0]=topshim_report 
Parameters: __WAITSTART__w__WAITEND__
14-10:19:11.122   confirm external command termination: "-10"
14-10:19:11.123   external cmd from "CPR": "topshim_report probe = Z168568_0015" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.123   cmd[0]=topshim_report probe = Z168568_0015 __WAITSTART__w__WAITEND__
14-10:19:11.124   confirm external command termination: "-10"
14-10:19:11.124   external cmd from "CPR": "topshim_report solvent = MeOD" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.125   cmd[0]=topshim_report solvent = MeOD __WAITSTART__w__WAITEND__
14-10:19:11.125   confirm external command termination: "-10"
14-10:19:11.126   external cmd from "CPR": "topshim_report shim nucleus = 2H" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.126   cmd[0]=topshim_report shim nucleus = 2H __WAITSTART__w__WAITEND__
14-10:19:11.127   confirm external command termination: "-10"
14-10:19:11.128   external cmd from "CPR": "topshim_report nucleus optimised for = 1H" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.128   cmd[0]=topshim_report nucleus optimised for = 1H __WAITSTART__w__WAITEND__
14-10:19:11.128   confirm external command termination: "-10"
14-10:19:11.129   external cmd from "CPR": "topshim_report o1p (from lock) = 3.30 ppm" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.129   cmd[0]=topshim_report o1p (from lock) = 3.30 ppm __WAITSTART__w__WAITEND__
14-10:19:11.130   confirm external command termination: "-10"
14-10:19:11.131   external cmd from "CPR": "topshim_report selective excitation width = 1.0 ppm" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.131   cmd[0]=topshim_report selective excitation width = 1.0 ppm __WAITSTART__w__WAITEND__
14-10:19:11.131   confirm external command termination: "-10"
14-10:19:11.132   external cmd from "CPR": "topshim_report 
Results:
" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:11.132   cmd[0]=topshim_report 
Results: __WAITSTART__w__WAITEND__
14-10:19:11.133   confirm external command termination: "-10"
14-10:19:11.134   external cmd from "CPR": "topshim_text 3 coil setup" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.134   cmd[0]=topshim_text 3 coil setup
14-10:19:11.155   external cmd from "CPR": "topshim_text 3 gradient setup" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.156   cmd[0]=topshim_text 3 gradient setup
14-10:19:11.156   external cmd from "CPR": "topshim_text 3 shim system setup" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.156   cmd[0]=topshim_text 3 shim system setup
14-10:19:11.654   external cmd from "CPR": "topshim_text 3 prepare lock" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.654   cmd[0]=topshim_text 3 prepare lock
14-10:19:11.737   external cmd from "CPR": "topshim_text 3 switch lock off" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.737   cmd[0]=topshim_text 3 switch lock off
14-10:19:11.832   external cmd from "CPR": "topshim_text 3 lock out" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-2"
14-10:19:11.832   cmd[0]=topshim_text 3 lock out
14-10:19:12.086   external cmd from "CPR": "disableautoacq" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1", option="-10"
14-10:19:12.086   cmd[0]=disableautoacq __WAITSTART__w__WAITEND__
14-10:19:12.087   confirm external command termination: "-10"
14-10:19:12.089   external cmd from "CPR": "topshim_text 3 experiment setup" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:12.089   cmd[0]=topshim_text 3 experiment setup
14-10:19:12.115   external cmd from "CPR": "-exch exchange.38088 wobbrout" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:12.115   cmd[0]=wobbrout __WAITSTART__w__WAITEND__
14-10:19:12.115   sendCommand wobbrout  on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:19:12.274   command wobbrout terminated
14-10:19:12.274   confirm external command termination: "-10"
14-10:19:12.289   external cmd from "CPR": "getprosol" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:12.289   cmd[0]=getprosol __WAITSTART__w__WAITEND__
14-10:19:12.310   Edprosol Program Version 3.000
 File Version: 4.000
14-10:19:12.400   Edprosol: in prosol  version  found : ProgramVersion =3.000    FileVersion    =4.000
14-10:19:12.416   File not found:
prosol/Z168568_0015/MeOD/2H_obs.4_16
prosol/Z168568_0015/generic/2H_obs.4_16
prosol/Z168568_0015/MeOD/2H_obs
Read file:
prosol/Z168568_0015/generic/2H_obs
14-10:19:12.699   getprosol finished!
14-10:19:12.708   allDatasetsMap entry removed, family: PARAM_ACQUISITION, 003526 : /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:19:12.709   confirm external command termination: "-10"
14-10:19:12.720   external cmd from "CPR": "-exch exchange.38088 wobbrout" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:12.720   cmd[0]=wobbrout __WAITSTART__w__WAITEND__
14-10:19:12.720   sendCommand wobbrout  on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:19:12.891   confirm external command termination: "-10"
14-10:19:12.891   command wobbrout terminated
14-10:19:12.914   external cmd from "CPR": "topshim_text 3 interpolate shim functions" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:12.914   cmd[0]=topshim_text 3 interpolate shim functions
14-10:19:12.918   external cmd from "CPR": "topshim_report 
1D Shimming:" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:12.918   cmd[0]=topshim_report 
1D Shimming: __WAITSTART__w__WAITEND__
14-10:19:12.919   confirm external command termination: "-10"
14-10:19:13.066   external cmd from "CPR": "noqu zg setcid=6 yes noBsmsAccess -noExpt" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:13.067   cmd[0]=noqu zg setcid=6 yes noBsmsAccess -noExpt
14-10:19:13.067   cmd[0]=zg setcid=6 yes noBsmsAccess -noExpt __WAITSTART__w__WAITEND__
14-10:19:13.067   external cmd from "CPR": "topshim_text 3 start acquisition" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:13.068   cmd[0]=topshim_text 3 start acquisition
14-10:19:13.068   cmd[0]=isNmr
14-10:19:13.097   added to parameterCacheList: 0
14-10:19:13.097   added to parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:19:13.097   removed from parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:19:13.097   parameterCacheFactoryMap cleared
14-10:19:13.097   sendCommand zg yes setcid=6  noBsmsAccess -noExpt tsUser=null on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:19:13.819   cmd[0]=ns
14-10:19:14.136   external cmd from "CPR": "topshim_text 3 acquire data" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:14.136   cmd[0]=topshim_text 3 acquire data
14-10:19:15.183   allDatasetsMap entry removed, family: PARAM_ACQUISITION, 003529 : /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:19:16.332   cmd[0]=setti
14-10:19:16.332   cmd[0]=edti
14-10:19:22.248   cmd[0]=acqustart ACQU POWCHK_ON
14-10:19:22.249   processEvent, got command from go: ACQU
14-10:19:22.249   added to parameterCacheList: 0
14-10:19:22.249   added to parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:19:23.855   cmd[0]=statusparsmodified
14-10:19:23.871   external cmd from "CPR": "topshim_text 3 2D Fourier transform" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:23.871   cmd[0]=topshim_text 3 2D Fourier transform
14-10:19:23.904   external cmd from "CPR": "noqu resume setcid=6" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:23.904   cmd[0]=noqu resume setcid=6
14-10:19:23.905   cmd[0]=resume setcid=6 __WAITSTART__w__WAITEND__
14-10:19:23.905   external cmd from "CPR": "topshim_text 3 acquire data" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:23.905   cmd[0]=topshim_text 3 acquire data
14-10:19:23.905   sendIrptCommand resume setcid=6
14-10:19:23.906   interrupt sent successfully
14-10:19:30.732   cmd[0]=statusparsmodified
14-10:19:30.740   external cmd from "CPR": "topshim_text 3 2D Fourier transform" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:30.740   cmd[0]=topshim_text 3 2D Fourier transform
14-10:19:30.772   external cmd from "CPR": "topshim_text 3 process field map" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:30.772   cmd[0]=topshim_text 3 process field map
14-10:19:30.773   external cmd from "CPR": "topshim_text 2 Evaluation of B0 map of iteration 1" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:30.774   cmd[0]=topshim_text 2 Evaluation of B0 map of iteration 1
14-10:19:30.908   external cmd from "CPR": "topshim_report Iteration  1: FWHM Before= 0.2 Hz, Target= 0.2 Hz" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:30.908   cmd[0]=topshim_report Iteration  1: FWHM Before= 0.2 Hz, Target= 0.2 Hz __WAITSTART__w__WAITEND__
14-10:19:30.909   confirm external command termination: "-10"
14-10:19:30.910   external cmd from "CPR": "topshim_report Shim succeeded
" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:30.910   cmd[0]=topshim_report Shim succeeded __WAITSTART__w__WAITEND__
14-10:19:30.911   confirm external command termination: "-10"
14-10:19:30.912   external cmd from "CPR": "topshim_report Lineshape quality factor (1D spectrum, spinning) = 0.261
" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:30.912   cmd[0]=topshim_report Lineshape quality factor (1D spectrum, spinning) = 0.261 __WAITSTART__w__WAITEND__
14-10:19:30.912   confirm external command termination: "-10"
14-10:19:30.917   external cmd from "CPR": "topshim_text 2 finish" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:30.917   cmd[0]=topshim_text 2 finish
14-10:19:30.917   external cmd from "CPR": "stop acq_only" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:30.917   cmd[0]=stop acq_only __WAITSTART__w__WAITEND__
14-10:19:30.918   sendIrptCommand stop acq_only
14-10:19:30.919   interrupt sent successfully
14-10:19:30.919   confirm external command termination: "-10"
14-10:19:30.920   external cmd from "CPR": "topshim_text 3 stop acquisition" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:30.920   cmd[0]=topshim_text 3 stop acquisition
14-10:19:30.924   cmd[0]=statusparsmodified
14-10:19:31.625   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:19:31.626   processStop called: dataset from ct: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1, dataset from GO: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:19:31.626   removed from parameterCache: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
14-10:19:31.633   command zg terminated on: /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1
14-10:19:33.166   external cmd from "CPR": "topshim_text 3 lock status" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:33.166   cmd[0]=topshim_text 3 lock status
14-10:19:41.705   external cmd from "CPR": "enableautoacq" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:41.705   cmd[0]=enableautoacq __WAITSTART__w__WAITEND__
14-10:19:41.705   confirm external command termination: "-10"
14-10:19:41.709   external cmd from "CPR": "topshim_report shim changes: none" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:41.709   cmd[0]=topshim_report shim changes: none __WAITSTART__w__WAITEND__
14-10:19:41.710   confirm external command termination: "-10"
14-10:19:41.712   external cmd from "CPR": "topshim_report 
duration = 31 sec" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:41.712   cmd[0]=topshim_report 
duration = 31 sec __WAITSTART__w__WAITEND__
14-10:19:41.712   confirm external command termination: "-10"
14-10:19:41.714   external cmd from "CPR": "topshim_report completed successfully" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:41.714   cmd[0]=topshim_report completed successfully __WAITSTART__w__WAITEND__
14-10:19:41.715   confirm external command termination: "-10"
14-10:19:41.717   external cmd from "CPR": "topshim_report finished Mon Sep 14 10:19:41 2026" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-10"
14-10:19:41.717   cmd[0]=topshim_report finished Mon Sep 14 10:19:41 2026 __WAITSTART__w__WAITEND__
14-10:19:41.717   confirm external command termination: "-10"
14-10:19:41.719   external cmd from "CPR": "topshim_status 0" on "/opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3/pdata/1", option="-2"
14-10:19:41.719   cmd[0]=topshim_status 0
14-10:19:41.720   CPR task 28 resumed normally, termstat=0
14-10:19:42.121   to CPR: "wrpa "1D_acquisition_data"  2698  1  "/opt/nmrdata/backupnmrdata/data/nmrafd/nmr" y" [id 29], COMMAND_WAIT_TERM
14-10:19:42.122   fromCPR loadAlert id = 11 message = /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
No such file or directory
14-10:19:42.123   wrpa : /opt/topspin3.7.0/data/topshimData/1D_acquisition_data/3
No such file or directory
14-10:19:44.521   toCPR sendAckn id=11 value=0
14-10:19:44.523   CPR task 29 resumed normally, termstat=-1
14-10:20:31.923   cmd[0]=_setti_save
14-10:20:32.003   cmd[0]=gdcheck comment
14-10:20:32.003   cmd[0]=isNmr
14-10:20:32.396   cmd[0]=_setti_save
14-10:20:32.411   cmd[0]=gdcheck comment
14-10:20:32.411   cmd[0]=isNmr
14-10:20:34.723   cmd[0]=`zg
14-10:20:34.730   cmd[0]=_checkcmd `zg
14-10:20:34.731   cmd[0]=_launchwithorwithoutdata _checkcmd `zg
14-10:20:34.734   external cmd from "CPR": "_checkcmd `CPR_ILLEGAL_COMMAND`
`zg" on "", option="-2"
14-10:20:34.734   cmd[0]=_checkcmd `CPR_ILLEGAL_COMMAND`
`zg
14-10:20:34.735   Command not implemented
`zg
14-10:20:36.563   cmd[0]=zg
14-10:20:36.563   cmd[0]=qu zg
14-10:20:36.565   [AbstractQueueJob] Executing job
14-10:20:36.566   cmd[0]=zg yes __WAITSTART__w__WAITEND__
14-10:20:36.567   cmd[0]=isNmr
14-10:20:36.572   cmd[0]=expt nowin "corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1"
14-10:20:36.573   sendCommand expt nowin "corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1" on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:36.597   added to parameterCacheList: 0
14-10:20:36.597   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:20:36.597   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:20:36.597   parameterCacheFactoryMap cleared
14-10:20:36.597   sendCommand zg yes  tsUser=null on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:36.611   cmd[0]=expt_setdata experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1" __WAITSTART__w__WAITEND__
14-10:20:36.612   [ExperimentTime::setData] Processing command thread request to set data 'experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1"'.
14-10:20:36.613   command expt terminated on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:38.333   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:20:44.298   cmd[0]=acqustart ACQU POWCHK_ON
14-10:20:44.299   processEvent, got command from go: ACQU
14-10:20:44.299   added to parameterCacheList: 0
14-10:20:44.299   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:20:44.324   cmd[0]=_create_drag_tranfer_handler
14-10:20:44.349   Entered MODULE de.bruker.nmr.sc.acq.impl.Module
14-10:20:44.349   adddataset corbaloc:iiop:127.0.0.1:5506/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:49.803   cmd[0]=checklockshift __WAITSTART__w__WAITEND__
14-10:20:49.803   to CPR: "checklockshift" [id 30], COMMAND_WAIT_TERM
14-10:20:50.328   cmd[0]=browse_update_last50
14-10:20:50.330   cmd[0]=add_datadir_automatic
14-10:20:50.495   CPR task 30 resumed normally, termstat=0
14-10:20:50.496   cmd[0]=teget nostatus
14-10:20:50.500   cmd[0]=statusparsmodified
14-10:20:50.500   cmd[0]=statusparsmodified
14-10:20:50.542   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:20:50.543   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:50.543   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1
14-10:20:50.552   command zg terminated
14-10:20:50.566   to CPR: "wrpa "z_atma_check_092026"  1001  1  "/opt/nmrdata/backupnmrdata/data/nmrafd/nmr" y" [id 31], COMMAND_WAIT_TERM
14-10:20:50.585   adddataset corbaloc:iiop:127.0.0.1:5506/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:50.585   CPR task 31 resumed normally, termstat=0
14-10:20:50.638   cmd[0]=browse_update_last50
14-10:20:50.640   cmd[0]=add_datadir_automatic
14-10:20:54.331   cmd[0]=efp
14-10:20:54.332   cmd[0]=isNmr
14-10:20:54.379   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:54.422   cmd[0]=browse_update_last50
14-10:20:54.423   cmd[0]=add_datadir_automatic
14-10:20:56.795   cmd[0]=bn
14-10:20:56.801   cmd[0]=xmac bn 
14-10:20:56.806   cmd[0]=_show_stat
14-10:20:56.833   cmd[0]=abs n __WAITSTART__w__WAITEND__
14-10:20:56.834   cmd[0]=isNmr
14-10:20:56.837   to CPR: "abs n" [id 32], COMMAND_WAIT_TERM
14-10:20:56.855   CPR task 32 resumed normally, termstat=0
14-10:20:56.855   cmd[0]=_show_stat
14-10:20:56.860   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:20:56.883   cmd[0]=browse_update_last50
14-10:20:56.884   cmd[0]=add_datadir_automatic
14-10:20:58.571   cmd[0]=.all
14-10:21:00.523   cmd[0]=bn
14-10:21:00.529   cmd[0]=xmac bn 
14-10:21:00.533   cmd[0]=_show_stat
14-10:21:00.556   cmd[0]=abs n __WAITSTART__w__WAITEND__
14-10:21:00.557   cmd[0]=isNmr
14-10:21:00.559   to CPR: "abs n" [id 33], COMMAND_WAIT_TERM
14-10:21:00.581   CPR task 33 resumed normally, termstat=0
14-10:21:00.582   cmd[0]=_show_stat
14-10:21:00.588   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:21:00.620   cmd[0]=browse_update_last50
14-10:21:00.622   cmd[0]=add_datadir_automatic
14-10:27:51.658   cmd[0]=iir
14-10:27:51.666   cmd[0]=xmac iir 
14-10:27:51.672   cmd[0]=_show_stat
14-10:27:51.694   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-10:27:51.699   to CPR: "kill cid 3" [id 34], COMMAND_WAIT_TERM
14-10:27:51.700   CPR task 34 resumed normally, termstat=0
14-10:27:51.700   delete ref file /opt/topspin3.7.0/prog/curdir/nmrafd/AcquisitionServer1.ref
14-10:27:51.701   command zg terminated
14-10:27:51.736   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:27:59.394   cmd[0]=i
14-10:27:59.400   cmd[0]=xmac i 
14-10:27:59.404   cmd[0]=_show_stat
14-10:27:59.430   cmd[0]=iexpno __WAITSTART__w__WAITEND__
14-10:27:59.436   cmd[0]=_checkcmd iexpno __WAITSTART__w__WAITEND__
14-10:27:59.437   cmd[0]=_launchwithorwithoutdata _checkcmd iexpno +~+~idsstart~+~+9312923621717077+~+~idend~+~+
14-10:27:59.440   external cmd from "CPR": "_checkcmd exec iexpno +~+~idsstart~+~+9312923621717077+~+~idend~+~+" on "", option="-2"
14-10:27:59.440   cmd[0]=_checkcmd exec iexpno +~+~idsstart~+~+9312923621717077+~+~idend~+~+
14-10:27:59.442   to CPR: "iexpno" [id 35], COMMAND_WAIT_TERM
14-10:27:59.484   external cmd from "CPR": "_replace_data /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1", option="-12"
14-10:27:59.484   cmd[0]=_replace_data /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1 __WAITSTART__w__WAITEND__
14-10:27:59.484   cmd[0]=dispdata
14-10:27:59.488   cmd[0]=browse_update_last50
14-10:27:59.489   cmd[0]=add_datadir_automatic
14-10:27:59.491   cmd[0]=_create_drag_tranfer_handler
14-10:27:59.492   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:27:59.493   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:27:59.503   confirm external command termination: "-12"
14-10:27:59.505   CPR task 35 resumed normally, termstat=0
14-10:27:59.506   cmd[0]=_show_stat
14-10:28:03.515   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-10:28:03.522   to CPR: "resetgab" [id 36], COMMAND_WAIT_TERM
14-10:28:03.590   CPR task 36 resumed normally, termstat=0
14-10:28:05.324   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:28:06.987   ii : Error during BirdRack flush:
Error in flush():
aqrackserver exception:
Error in "flush": Hppr error in clearErrors: Probe has changed or is not yet defined.
Please do 'edprobe' first and define a 'current probe'.
14-10:28:13.673   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:28:13.674   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:28:16.722   command ii terminated
14-10:28:16.723   cmd[0]=_show_stat
14-10:28:17.594   cmd[0]=edprobe
14-10:28:29.585   Probe changed
14-10:28:29.662   BSVT_HEATER_SAFETY_TEMPERATURE
14-10:28:46.146   cmd[0]=iir
14-10:28:46.152   cmd[0]=xmac iir 
14-10:28:46.157   cmd[0]=_show_stat
14-10:28:46.180   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-10:28:46.184   to CPR: "kill cid 7" [id 37], COMMAND_WAIT_TERM
14-10:28:46.184   CPR task 37 resumed normally, termstat=0
14-10:28:46.185   delete ref file /opt/topspin3.7.0/prog/curdir/nmrafd/AcquisitionServer1.ref
14-10:28:46.185   command ii terminated
14-10:28:46.235   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:28:58.006   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-10:28:58.018   to CPR: "resetgab" [id 38], COMMAND_WAIT_TERM
14-10:28:58.082   CPR task 38 resumed normally, termstat=0
14-10:28:59.795   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:29:13.154   cmd[0]=statusparsmodified
14-10:29:13.254   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:29:13.255   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:29:13.261   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:29:16.296   command ii terminated
14-10:29:16.297   cmd[0]=_show_stat
14-10:29:21.698   cmd[0]=w
14-10:29:21.704   cmd[0]=xmac w 
14-10:29:21.708   cmd[0]=_show_stat
14-10:29:21.728   cmd[0]=qu atma coarse __WAITSTART__w__WAITEND__
14-10:29:21.730   [AbstractQueueJob] Executing job
14-10:29:21.730   cmd[0]=atma coarse __WAITSTART__w__WAITEND__
14-10:29:21.731   cmd[0]=isNmr
14-10:29:21.732   cmd[0]=_show_stat
14-10:29:21.763   to CPR: "atma coarse" [id 39], COMMAND_WAIT_TERM
14-10:29:27.082   fromCPR loadAlert id = 13 message = Error in Probe Data Initialization:
Cannot read ATMA PICS from motor unit:
Could not read the ATMA identification
14-10:29:27.083   atma : Error in Probe Data Initialization:
Cannot read ATMA PICS from motor unit:
Could not read the ATMA identification
14-10:30:45.246   toCPR sendAckn id=13 value=0
14-10:30:45.257   CPR task 39 resumed normally, termstat=-2
14-10:30:45.257   Priority job 'PriorityQueueJob[id=8, command=atma coarse, priority=0, dataObject=/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1]' returned error code -2 for command 'atma coarse'.
14-10:34:30.434   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:34:30.447   cmd[0]=browse_update_last50
14-10:34:30.448   cmd[0]=add_datadir_automatic
14-10:34:30.451   cmd[0]=_create_drag_tranfer_handler
14-10:34:30.453   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:34:30.455   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:34:38.106   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:34:38.119   cmd[0]=browse_update_last50
14-10:34:38.119   cmd[0]=add_datadir_automatic
14-10:34:38.120   cmd[0]=_create_drag_tranfer_handler
14-10:34:38.123   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:34:38.124   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:34:41.009   cmd[0]=iir
14-10:34:41.016   cmd[0]=xmac iir 
14-10:34:41.021   cmd[0]=_show_stat
14-10:34:41.042   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-10:34:41.046   to CPR: "kill cid 8" [id 40], COMMAND_WAIT_TERM
14-10:34:41.047   CPR task 40 resumed normally, termstat=0
14-10:34:41.048   delete ref file /opt/topspin3.7.0/prog/curdir/nmrafd/AcquisitionServer1.ref
14-10:34:41.048   command ii terminated
14-10:34:41.101   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:34:52.873   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-10:34:52.881   to CPR: "resetgab" [id 41], COMMAND_WAIT_TERM
14-10:34:52.980   CPR task 41 resumed normally, termstat=0
14-10:34:54.716   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:34:56.229   ii : Error during BirdRack flush:
Error in flush():
aqrackserver exception:
Error in "flush": Hppr error in clearErrors: Probe has changed or is not yet defined.
Please do 'edprobe' first and define a 'current probe'.
14-10:35:01.049   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:35:01.049   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:35:04.016   command ii terminated
14-10:35:04.018   cmd[0]=_show_stat
14-10:35:04.705   cmd[0]=edprobe
14-10:35:17.321   Probe changed
14-10:35:17.381   BSVT_HEATER_SAFETY_TEMPERATURE
14-10:35:26.490   allDatasetsMap entry removed, family: PARAM_PROBE_USEMODE, null : UZ168568_0015
14-10:35:39.873   cmd[0]=iir
14-10:35:39.880   cmd[0]=xmac iir 
14-10:35:39.883   cmd[0]=_show_stat
14-10:35:39.912   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-10:35:39.916   to CPR: "kill cid 9" [id 42], COMMAND_WAIT_TERM
14-10:35:39.917   CPR task 42 resumed normally, termstat=0
14-10:35:39.917   delete ref file /opt/topspin3.7.0/prog/curdir/nmrafd/AcquisitionServer1.ref
14-10:35:39.918   command ii terminated
14-10:35:39.966   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:35:49.690   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:35:49.700   cmd[0]=browse_update_last50
14-10:35:49.700   cmd[0]=add_datadir_automatic
14-10:35:49.704   cmd[0]=_create_drag_tranfer_handler
14-10:35:49.706   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:35:49.707   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:35:50.737   cmd[0]=setti
14-10:35:50.738   cmd[0]=edti
14-10:35:51.756   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-10:35:51.763   to CPR: "resetgab" [id 43], COMMAND_WAIT_TERM
14-10:35:51.830   CPR task 43 resumed normally, termstat=0
14-10:35:53.568   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:36:05.992   cmd[0]=statusparsmodified
14-10:36:06.089   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:36:06.090   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:36:08.201   cmd[0]=_setti_save
14-10:36:08.610   cmd[0]=_setti_save
14-10:36:08.991   command ii terminated
14-10:36:08.992   cmd[0]=_show_stat
14-10:36:14.457   cmd[0]=setti
14-10:36:14.458   cmd[0]=edti
14-10:36:20.593   cmd[0]=_setti_save
14-10:36:21.980   cmd[0]=_setti_save
14-10:36:25.937   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:36:25.947   cmd[0]=browse_update_last50
14-10:36:25.948   cmd[0]=add_datadir_automatic
14-10:36:25.951   cmd[0]=_create_drag_tranfer_handler
14-10:36:25.953   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:36:25.954   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/2/pdata/1
14-10:36:28.649   cmd[0]=i
14-10:36:28.655   cmd[0]=xmac i 
14-10:36:28.660   cmd[0]=_show_stat
14-10:36:28.692   cmd[0]=iexpno __WAITSTART__w__WAITEND__
14-10:36:28.695   cmd[0]=_checkcmd iexpno __WAITSTART__w__WAITEND__
14-10:36:28.696   cmd[0]=_launchwithorwithoutdata _checkcmd iexpno +~+~idsstart~+~+9313432881121020+~+~idend~+~+
14-10:36:28.700   external cmd from "CPR": "_checkcmd exec iexpno +~+~idsstart~+~+9313432881121020+~+~idend~+~+" on "", option="-2"
14-10:36:28.700   cmd[0]=_checkcmd exec iexpno +~+~idsstart~+~+9313432881121020+~+~idend~+~+
14-10:36:28.701   to CPR: "iexpno" [id 44], COMMAND_WAIT_TERM
14-10:36:28.745   external cmd from "CPR": "_replace_data /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1", option="-14"
14-10:36:28.745   cmd[0]=_replace_data /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1 __WAITSTART__w__WAITEND__
14-10:36:28.745   cmd[0]=dispdata
14-10:36:28.750   cmd[0]=browse_update_last50
14-10:36:28.751   cmd[0]=add_datadir_automatic
14-10:36:28.753   cmd[0]=_create_drag_tranfer_handler
14-10:36:28.754   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:36:28.755   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:36:28.765   confirm external command termination: "-14"
14-10:36:28.768   CPR task 44 resumed normally, termstat=0
14-10:36:28.768   cmd[0]=_show_stat
14-10:36:32.265   cmd[0]=setti
14-10:36:32.265   cmd[0]=edti
14-10:36:41.649   cmd[0]=_setti_save
14-10:36:42.251   cmd[0]=_setti_save
14-10:36:44.849   cmd[0]=iir
14-10:36:44.855   cmd[0]=xmac iir 
14-10:36:44.859   cmd[0]=_show_stat
14-10:36:44.882   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-10:36:44.886   to CPR: "kill cid 10" [id 45], COMMAND_WAIT_TERM
14-10:36:44.888   CPR task 45 resumed normally, termstat=0
14-10:36:44.889   delete ref file /opt/topspin3.7.0/prog/curdir/nmrafd/AcquisitionServer1.ref
14-10:36:44.890   command ii terminated
14-10:36:44.933   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:36:56.708   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-10:36:56.719   to CPR: "resetgab" [id 46], COMMAND_WAIT_TERM
14-10:36:56.780   CPR task 46 resumed normally, termstat=0
14-10:36:58.502   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:37:09.835   cmd[0]=statusparsmodified
14-10:37:09.934   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:37:09.935   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:37:09.941   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:37:12.836   command ii terminated
14-10:37:12.837   cmd[0]=_show_stat
14-10:37:16.681   cmd[0]=w
14-10:37:16.687   cmd[0]=xmac w 
14-10:37:16.691   cmd[0]=_show_stat
14-10:37:16.712   cmd[0]=qu atma coarse __WAITSTART__w__WAITEND__
14-10:37:16.714   [AbstractQueueJob] Executing job
14-10:37:16.715   cmd[0]=atma coarse __WAITSTART__w__WAITEND__
14-10:37:16.715   cmd[0]=isNmr
14-10:37:16.716   cmd[0]=_show_stat
14-10:37:16.761   to CPR: "atma coarse" [id 47], COMMAND_WAIT_TERM
14-10:37:20.766   external cmd from "CPR": "-exch exchange.37473 wobbrout" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1", option="-6"
14-10:37:20.766   cmd[0]=wobbrout __WAITSTART__w__WAITEND__
14-10:37:20.766   sendCommand wobbrout  on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:37:20.931   confirm external command termination: "-6"
14-10:37:20.931   command wobbrout terminated
14-10:41:43.638   cmd[0]=browse_update_last50
14-10:41:43.640   cmd[0]=add_datadir_automatic
14-10:41:43.642   external cmd from "CPR": "wobb noLED f1  var 256 9.000000" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1", option="-2"
14-10:41:43.642   cmd[0]=browse_update_last50
14-10:41:43.643   cmd[0]=wobb noLED f1  var 256 9.000000
14-10:41:43.644   cmd[0]=add_datadir_automatic
14-10:41:43.648   added to parameterCacheList: 0
14-10:41:43.648   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:41:43.648   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:41:43.648   parameterCacheFactoryMap cleared
14-10:41:43.648   sendCommand wobb noLED f1  var 256 9.000000 tsUser=null on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:41:57.316   cmd[0]=acqustart WOBB POWCHK_ON 1
14-10:41:57.317   processEvent, got command from go: WOBB
14-10:41:57.317   added to parameterCacheList: 0
14-10:41:57.317   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:41:58.798   cmd[0]=_create_drag_tranfer_handler
14-10:41:58.816   Entered MODULE de.bruker.nmr.sc.acq.impl.Module
14-10:41:58.816   adddataset corbaloc:iiop:127.0.0.1:5506/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:42:24.772   external cmd from "CPR": "halt" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1", option="-6"
14-10:42:24.772   cmd[0]=halt __WAITSTART__w__WAITEND__
14-10:42:24.773   sendIrptCommand halt 
14-10:42:24.774   interrupt sent successfully
14-10:42:24.774   confirm external command termination: "-6"
14-10:42:24.780   cmd[0]=statusparsmodified
14-10:42:25.379   cmd[0]=acqustop end POWCHK_ON WOBB
14-10:42:25.380   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:42:25.380   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:42:25.386   adddataset corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:42:28.431   command wobb terminated on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:42:28.777   external cmd from "CPR": "halt" on "/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1", option="-6"
14-10:42:28.777   cmd[0]=halt __WAITSTART__w__WAITEND__
14-10:42:28.778   sendIrptCommand halt 
14-10:42:28.778   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:42:28.779   confirm external command termination: "-6"
14-10:42:28.784   cmd[0]=browse_update_last50
14-10:42:28.785   cmd[0]=add_datadir_automatic
14-10:42:28.787   cmd[0]=browse_update_last50
14-10:42:28.788   cmd[0]=add_datadir_automatic
14-10:42:29.960   CPR task 47 resumed normally, termstat=0
14-10:45:17.040   cmd[0]=zg
14-10:45:17.040   cmd[0]=qu zg
14-10:45:17.042   [AbstractQueueJob] Executing job
14-10:45:17.043   cmd[0]=zg yes __WAITSTART__w__WAITEND__
14-10:45:17.043   cmd[0]=isNmr
14-10:45:17.049   cmd[0]=expt nowin "corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1"
14-10:45:17.050   sendCommand expt nowin "corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1" on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:45:17.074   added to parameterCacheList: 0
14-10:45:17.074   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:45:17.075   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:45:17.075   parameterCacheFactoryMap cleared
14-10:45:17.075   Cryo Probe error:

NMR acquisition is disabled because the Cryo Probe is not cold.
If you need to perform an NMR acquisition while the Cryo Probe is not cold,
you may temporarily allow NMR acquisition in the CryoDisplay Configuration tab.
14-10:45:17.075   Priority job 'PriorityQueueJob[id=10, command=zg yes, priority=0, dataObject=/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1]' returned error code -105 for command 'zg yes'.
14-10:45:17.097   cmd[0]=expt_setdata experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1" __WAITSTART__w__WAITEND__
14-10:45:17.097   [ExperimentTime::setData] Processing command thread request to set data 'experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5505/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1"'.
14-10:45:17.100   command expt terminated
14-10:45:25.352   cmd[0]=exit
14-10:45:25.353   cmd[0]=acqurunning
14-10:45:25.358   to CPR: "show status" [id 48], COMMAND_WAIT_TERM
14-10:45:25.364   CPR task 48 resumed normally, termstat=0
14-10:45:25.364   exit: parsing cprstatus file for list of clients
14-10:45:25.374   exit: checking for other clients
14-10:45:25.912   exit: regular shutdown initiated.
14-10:45:25.940   command wobb terminated
14-10:45:26.063   exit: ORB is down.
14-10:45:26.064   org.omg.CORBA.COMM_FAILURE:   vmcid: 0x0  minor code: 0 completed: Maybe

  2026-09-14
---------------
14-10:45:42.052   Graphical User Interface started.
14-10:45:42.053   Operating system = LINUX, JVM type (bit) = 64

14-10:45:42.053   User properties directory=/home/nmrafd/.topspin-av600/prop
14-10:45:42.652   GUI Version = TopSpin 3.7.0 / local
14-10:45:42.696   cmd=_create_drag_tranfer_handler
14-10:45:42.829   To connect to this TOPSPIN instance via Remote Connection use host=132.229.121.209, port=5500
14-10:45:42.830   Start: opening file for local session key at '/opt/topspin3.7.0/prog/curdir/nmrafd/keystore/localSession.key'
14-10:45:42.830   Start: local session key imported successfully.
14-10:45:42.831   Start: GUI session key created
14-10:45:42.831   cmd=init_cpr
14-10:45:42.833   Start ORB: recommended port=5090.
14-10:45:42.883   Start ORB: successful on port 5090
14-10:45:42.884   cmd=cprlistener
14-10:45:42.886   Start CprListenerServer: activate.
14-10:45:42.903   Start CprListenerServer: activation successful, id = corbaloc:iiop:132.229.121.209:5090/2861354678/CtrlPOA/CPRClient, localized id = corbaloc:iiop:127.0.0.1:5090/2861354678/CtrlPOA/CPRClient
14-10:45:43.086   Contact configuration service: URL=corbaloc:iiop:132.229.121.209:5500/ConfigurationServer, IOR=IOR:010000002400000049444c3a6272756b65722e64652f6370722f436f6e66696775726174696f6e3a312e3000010000000000000064000000010102000a0000003132372e302e302e31007c1513000000436f6e66696775726174696f6e536572766572000200000000000000080000000100000000545441010000001c00000001000000010001000100000001000105090101000100000009010100
14-10:45:43.087   Contact configuration service: local contact, using URL: corbaloc:iiop:127.0.0.1:5500/ConfigurationServer
14-10:45:43.116   Contact configuration service: successful.
14-10:45:43.116   Contact configuration service: local session, authentication done.
14-10:45:43.119   Contact CPR: local contact, narrowing object for: corbaloc:iiop:127.0.0.1:5500/CPRMsg, IOR=IOR:010000001d00000049444c3a6272756b65722e64652f6370722f4350524d73673a312e3000000000010000000000000058000000010102000a0000003132372e302e302e31007c15060000004350524d736700000200000000000000080000000100000000545441010000001c00000001000000010001000100000001000105090101000100000009010100
14-10:45:43.123   Contact CPR: object is valid.
14-10:45:43.123   Contact CPR: address of CprListener is corbaloc:iiop:132.229.121.209:5090/2861354678/CtrlPOA/CPRClient
14-10:45:43.123   Contact CPR: login at CPR with "corbaloc:iiop:127.0.0.1:5090/2861354678/CtrlPOA/CPRClient"
14-10:45:43.375   Contact CPR: attempt #0 to get assigned client id...
14-10:45:43.375   Contact CPR: successful, got id '1'.
14-10:45:43.376   CPR connection successful, client id = 1
14-10:45:43.377   Start dataserver: launching command, waiting on result
14-10:45:43.378   to CPR: "dataserver id 1 1789375543378" [id 1], COMMAND_WAIT_START
14-10:45:43.418   CPR task 1 resumed normally, termstat=0
14-10:45:43.421   fromCPR loadAlert id = 0 message = ~~~~~1789375543378 DATASERVER=OK
14-10:45:43.519   Start dataserver: result string is: DATASERVER=OK
14-10:45:43.519   Start dataserver: getting current address
14-10:45:43.520   Start dataserver: URL=corbaloc:iiop:132.229.121.209:5507/NMRDataServer, IOR=IOR:010000002800000049444c3a6272756b65722e64652f646174612f4469736346756e6350726f76696465723a312e300001000000000000009800000001010200100000003133322e3232392e3132312e3230390083150000110000004e4d5244617461536572766572312e31300000000400000000000000080000000100000000545441010000001c000000010000000100010001000000010001050901010001000000090101000300000014000000010000000a0000003132372e302e302e310083151400000008000000010066006600b515
14-10:45:43.521   Start dataserver: local contact, using URL: corbaloc:iiop:127.0.0.1:5507/NMRDataServer
14-10:45:43.528   Start dataserver: path to logfile is "/opt/topspin3.7.0/prog/curdir/nmrafd/stdout.dataserver.39870"
14-10:45:43.528   Start dataserver: successful.
14-10:45:43.529   cmd=_start_browsers
14-10:45:43.619   cmd=_show_lic
14-10:45:43.620   getLicense called: attribute
14-10:45:43.621   to CPR: "checkout_license verbose attribute" [id 2], COMMAND_WAIT_TERM
14-10:45:43.623   external cmd from "CPR": "lic_attribute" on "", option="-2"
14-10:45:43.623   cmd=lic_attribute
14-10:45:43.624   CPR task 2 resumed normally, termstat=0
14-10:45:43.625   getAttribute called: 0
14-10:45:43.625   wait for license attribute
14-10:45:43.625   License type: 
14-10:45:43.626   get license attribute finished
14-10:45:43.626   cmd=_set_cprhist
14-10:45:43.705   cmd=configuretopspin
14-10:45:43.707   Installation configured with curinst: AVANCEIII600
14-10:45:43.795   LOCKCONF, SamChgEth[0-3]: [DLM, 149.236.99.55, no, no, no, tty04]
14-10:45:43.828   Datastation: false, ELCB: true, EVTU: false, BACSAIR: true, MAS/2: false, MAS Unit: false,
                    VTU: true, Prodigy: false, RCU: false, Multi-receiver: false, number of receivers: 1,
                    Multipeak lock: true, NMR thermometer: false, 19F lock: false, SampleChanger: true, SampleXpress over Ethernet: false,
                    Lock: true, SoftLock: false
14-10:45:43.831   Trying to start hardware access servers...
14-10:45:43.867   FidStoreServer0 successfully started
14-10:45:43.868   cmd=stopsoapprobeserver
14-10:45:43.869   cmd=stopsoapexternalsampletransportserver
14-10:45:43.869   cmd=stopsoapnotificationserver
14-10:45:43.870   cmd=startwatcher
14-10:45:43.870   cmd=connecttocryo
14-10:45:43.871   cmd=startbsmsserver
14-10:45:43.872   Do not publish
14-10:45:43.873   Start BsmsServer
14-10:45:43.913   cmd=login start
14-10:45:43.916   cmd=runonce
14-10:45:43.919   Option: null
14-10:45:43.919   cmd=license
14-10:45:43.921   License code: CheckExpireDate
14-10:45:43.921   to CPR: "checkout_license expire spect XWINNMR" [id 3], COMMAND_WAIT_TERM
14-10:45:43.995   Start BsmsServer: activation successful, id: corbaloc:iiop:132.229.121.209:5090/2861354678/CtrlPOA/BsmsServer
14-10:45:43.997   cmd=checkbsmsfirmwareversion
14-10:45:44.147   external cmd from "CPR": "lic_expire_date 2037-09-28T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:45:44.147   cmd=lic_expire_date 2037-09-28T17:14:36+02:00
14-10:45:44.148   CPR task 3 resumed normally, termstat=0
14-10:45:44.227   cmd=expinstallcheck
14-10:45:44.228   cmd=_db_cleanxml
14-10:45:44.228   cmd=startspooler
14-10:45:44.229   cmd=mons
14-10:45:44.232   cmd=monacb
14-10:45:44.236   cmd=montemp
14-10:45:44.236   cmd=monpowindicator
14-10:45:44.237   cmd=monbsmstemp
14-10:45:44.240   cmd=monbsms
14-10:45:44.251   cmd=monlock
14-10:45:44.252   cmd=monacq
14-10:45:44.256   cmd=montempRun
14-10:45:44.278   cmd=monbsmsstatus
14-10:45:44.291   to CPR: "checkout_license expire no_checkout 1011013000:TOPSPIN3" [id 4], COMMAND_WAIT_TERM
14-10:45:44.294   cmd=montime
14-10:45:44.295   cmd=monspooler
14-10:45:44.298   BasicSampleChanger arguments: topspinGen='ts3' ipAddress=149.236.99.1 logFileSize=104857600 restartServerIpAddress=132.229.121.209 restartServerPort=5520
14-10:45:44.300   cmd=monacqrun
14-10:45:44.301   cmd=monlockRun
14-10:45:44.306   cmd=monacbrun
14-10:45:44.370   Cryo: Firmware "20190123" (00.02), XT support: true
14-10:45:44.462   external cmd from "CPR": "lic_expire_date 2037-09-28T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:45:44.462   cmd=lic_expire_date 2037-09-28T17:14:36+02:00
14-10:45:44.473   CPR task 4 resumed normally, termstat=0
14-10:45:44.474   to CPR: "checkout_license expire no_checkout 1011140100:SMP_CMCA" [id 5], COMMAND_WAIT_TERM
14-10:45:44.513   Suitable firmware found: 230420
14-10:45:44.513   Initializing coil temperature values
14-10:45:44.598   cmd=stopbsmssoapserver
14-10:45:44.602   Stopping BsmsSoapServer
14-10:45:44.626   external cmd from "CPR": "lic_expire_date 2023-09-07T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:45:44.626   cmd=lic_expire_date 2023-09-07T17:14:36+02:00
14-10:45:44.626   CPR task 5 resumed normally, termstat=0
14-10:45:44.627   to CPR: "checkout_license expire no_checkout 1011140200:SMP_CMCA_CLASSROOM" [id 6], COMMAND_WAIT_TERM
14-10:45:44.685   CPR task 6 resumed normally, termstat=-1
14-10:45:44.685   Error checking expire date for '1011140200:SMP_CMCA_CLASSROOM, -1
14-10:45:44.686   to CPR: "checkout_license expire no_checkout 1011160100:SMP_CMCQ" [id 7], COMMAND_WAIT_TERM
14-10:45:44.704   Error getting target temperature value from VTU: Unable to get parameter VTC_PROBE_CH
14-10:45:44.720   CPR task 7 resumed normally, termstat=-1
14-10:45:44.720   Error checking expire date for '1011160100:SMP_CMCQ, -1
14-10:45:44.721   to CPR: "checkout_license expire no_checkout 1021290200:FBS_ADVANCED" [id 8], COMMAND_WAIT_TERM
14-10:45:44.787   CPR task 8 resumed normally, termstat=-1
14-10:45:44.787   Error checking expire date for '1021290200:FBS_ADVANCED, -1
14-10:45:44.788   to CPR: "checkout_license expire no_checkout 1011330100:SMP_POTENCY_MR" [id 9], COMMAND_WAIT_TERM
14-10:45:44.843   CPR task 9 resumed normally, termstat=-1
14-10:45:44.844   Error checking expire date for '1011330100:SMP_POTENCY_MR, -1
14-10:45:44.844   to CPR: "checkout_license expire no_checkout 1011320100:SMP_SMART_DRIVE_NMR" [id 10], COMMAND_WAIT_TERM
14-10:45:44.904   CPR task 10 resumed normally, termstat=-1
14-10:45:44.904   Error checking expire date for '1011320100:SMP_SMART_DRIVE_NMR, -1
14-10:45:44.904   to CPR: "checkout_license expire no_checkout 1011150100:SMP_STRUCELU" [id 11], COMMAND_WAIT_TERM
14-10:45:45.103   external cmd from "CPR": "lic_expire_date 2023-09-07T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:45:45.103   cmd=lic_expire_date 2023-09-07T17:14:36+02:00
14-10:45:45.104   CPR task 11 resumed normally, termstat=0
14-10:45:45.104   to CPR: "checkout_license expire no_checkout 1011150200:SMP_STRUCELU_CLASSROOM" [id 12], COMMAND_WAIT_TERM
14-10:45:45.147   CPR task 12 resumed normally, termstat=-1
14-10:45:45.147   Error checking expire date for '1011150200:SMP_STRUCELU_CLASSROOM, -1
14-10:45:45.147   to CPR: "checkout_license expire no_checkout 1021030000:TOPSPIN_NUS" [id 13], COMMAND_WAIT_TERM
14-10:45:45.210   CPR task 13 resumed normally, termstat=-1
14-10:45:45.211   Error checking expire date for '1021030000:TOPSPIN_NUS, -1
14-10:45:45.211   to CPR: "checkout_license expire no_checkout 1021230100:ASSURE-SST" [id 14], COMMAND_WAIT_TERM
14-10:45:45.401   external cmd from "CPR": "lic_expire_date 2023-09-07T17:14:36+02:00" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:45:45.402   cmd=lic_expire_date 2023-09-07T17:14:36+02:00
14-10:45:45.403   CPR task 14 resumed normally, termstat=0
14-10:45:45.405   to CPR: "checkout_license expire no_checkout 1021230200:AUTO_CALIBRATE" [id 15], COMMAND_WAIT_TERM
14-10:45:45.453   CPR task 15 resumed normally, termstat=-1
14-10:45:45.454   Error checking expire date for '1021230200:AUTO_CALIBRATE, -1
14-10:45:50.457   Address used for BasicSampleChanger: 149.236.99.1:9999, changer address: 149.236.99.55
14-10:45:56.117   cmd=_create_drag_tranfer_handler
14-10:45:56.130   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:45:56.160   cmd[0]=browse_update_last50
14-10:45:56.162   cmd[0]=add_datadir_automatic
14-10:45:56.185   cmd[0]=_create_drag_tranfer_handler
14-10:45:56.188   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:45:56.189   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z/1/pdata/1
14-10:46:01.764   cmd[0]=cryodisp
14-10:46:01.785   cmd[0]=_create_drag_tranfer_handler
14-10:46:10.335   cmd[0]=re /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:46:10.349   cmd[0]=browse_update_last50
14-10:46:10.350   cmd[0]=add_datadir_automatic
14-10:46:10.353   cmd[0]=_create_drag_tranfer_handler
14-10:46:10.356   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:46:10.359   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:46:13.016   cmd[0]=iir
14-10:46:13.022   cmd[0]=xmac iir 
14-10:46:13.028   cmd[0]=_show_stat
14-10:46:14.483   cmd[0]=ii restart __WAITSTART__w__WAITEND__
14-10:46:14.519   sendCommand ii restart on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:46:26.350   cmd[0]=xcpr resetgab __WAITSTART__w__WAITEND__
14-10:46:26.368   to CPR: "resetgab" [id 16], COMMAND_WAIT_TERM
14-10:46:27.320   CPR task 16 resumed normally, termstat=0
14-10:46:29.091   cmd[0]=xcpr bsmscmd LOCKMUTE false
14-10:46:45.182   cmd[0]=statusparsmodified
14-10:46:45.183   cmd[0]=statparsmodifiedworker
14-10:46:45.279   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:46:45.280   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:46:45.295   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:46:48.182   command ii terminated
14-10:46:48.184   cmd[0]=_show_stat
14-10:46:50.696   external cmd from "CPR": "blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad44d312@@deviceState@@device code:0x60000001: Device is up
" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:46:50.701   cmd[0]=blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad44d312@@deviceState@@device code:0x60000001: Device is up
14-10:46:50.703   sendIrptCommand blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad44d312@@deviceState@@device code:0x60000001: Device is up
14-10:46:50.703   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:46:50.796   external cmd from "CPR": "blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad44d3120001@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:46:50.797   cmd[0]=blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad44d3120001@@status@@127
14-10:46:50.798   sendIrptCommand blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad44d3120001@@status@@127
14-10:46:50.798   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:46:51.098   external cmd from "CPR": "blac_event @@0@@BLACHService2@@bruker-com-BLA-0000ad44d3120002@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:46:51.099   cmd[0]=blac_event @@0@@BLACHService2@@bruker-com-BLA-0000ad44d3120002@@status@@127
14-10:46:51.100   sendIrptCommand blac_event @@0@@BLACHService2@@bruker-com-BLA-0000ad44d3120002@@status@@127
14-10:46:51.100   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:46:51.802   external cmd from "CPR": "blac_event @@0@@BLACHService3@@bruker-com-BLA-0000ad44d3120003@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:46:51.803   cmd[0]=blac_event @@0@@BLACHService3@@bruker-com-BLA-0000ad44d3120003@@status@@127
14-10:46:51.804   sendIrptCommand blac_event @@0@@BLACHService3@@bruker-com-BLA-0000ad44d3120003@@status@@127
14-10:46:51.804   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:46:52.819   external cmd from "CPR": "blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad4c4d12@@deviceState@@device code:0x60000001: Device is up
" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:46:52.819   cmd[0]=blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad4c4d12@@deviceState@@device code:0x60000001: Device is up
14-10:46:52.820   sendIrptCommand blac_event @@0@@BrukerCommonService@@bruker-com-BLA-0000ad4c4d12@@deviceState@@device code:0x60000001: Device is up
14-10:46:52.821   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:46:52.919   external cmd from "CPR": "blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad4c4d120001@@status@@127" on "/UndefinedDir/UndefinedName/1/pdata/1", option="-2"
14-10:46:52.920   cmd[0]=blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad4c4d120001@@status@@127
14-10:46:52.922   sendIrptCommand blac_event @@0@@BLACHService1@@bruker-com-BLA-0000ad4c4d120001@@status@@127
14-10:46:52.922   unable to send interrupt: receiveThreadRunning: true refCounter: 0
14-10:48:51.008   cmd[0]=zg
14-10:48:51.009   cmd[0]=qu zg
14-10:48:51.013   [AbstractQueueJob] Executing job
14-10:48:51.014   cmd[0]=zg yes __WAITSTART__w__WAITEND__
14-10:48:51.021   cmd[0]=isNmr
14-10:48:51.042   cmd[0]=expt nowin "corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1"
14-10:48:51.060   added to parameterCacheList: 0
14-10:48:51.061   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:48:51.061   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:48:51.061   parameterCacheFactoryMap cleared
14-10:48:51.062   sendCommand zg yes  tsUser=null on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:48:51.101   sendCommand expt nowin "corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1" on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:48:51.209   cmd[0]=expt_setdata experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1" __WAITSTART__w__WAITEND__
14-10:48:51.211   [ExperimentTime::setData] Processing command thread request to set data 'experimenttime="4" requireddiskspace="386 
" freediskspace="1.70389e+12" id="corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1"'.
14-10:48:51.216   command expt terminated on: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:48:54.964   cmd[0]=lockdisp
14-10:48:54.972   cmd[0]=_create_drag_tranfer_handler
14-10:48:55.005   cmd[0]=_create_drag_tranfer_handler
14-10:48:55.008   Entered MODULE de.bruker.nmr.sc.lockdisp.impl.Module
14-10:48:55.008   adddataset 132.229.121.209
14-10:48:55.518   cmd[0]=acqustart ACQU POWCHK_ON
14-10:48:55.518   processEvent, got command from go: ACQU
14-10:48:55.519   added to parameterCacheList: 0
14-10:48:55.519   added to parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:48:55.549   cmd[0]=_create_drag_tranfer_handler
14-10:48:55.577   Entered MODULE de.bruker.nmr.sc.acq.impl.Module
14-10:48:55.577   adddataset corbaloc:iiop:127.0.0.1:5508/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:01.031   cmd[0]=checklockshift __WAITSTART__w__WAITEND__
14-10:49:01.032   to CPR: "checklockshift" [id 17], COMMAND_WAIT_TERM
14-10:49:01.586   cmd[0]=browse_update_last50
14-10:49:01.587   cmd[0]=add_datadir_automatic
14-10:49:01.786   CPR task 17 resumed normally, termstat=0
14-10:49:01.787   cmd[0]=teget nostatus
14-10:49:01.791   cmd[0]=statusparsmodified
14-10:49:01.793   cmd[0]=statusparsmodified
14-10:49:01.801   cmd[0]=acqustop end POWCHK_ON ACQU
14-10:49:01.801   processStop called: dataset from ct: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1, dataset from GO: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:01.802   removed from parameterCache: /opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3
14-10:49:01.811   command zg terminated
14-10:49:01.818   to CPR: "wrpa "z_atma_check_092026"  3  1  "/opt/nmrdata/backupnmrdata/data/nmrafd/nmr" y" [id 18], COMMAND_WAIT_TERM
14-10:49:01.823   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:01.839   CPR task 18 resumed normally, termstat=0
14-10:49:01.856   adddataset corbaloc:iiop:127.0.0.1:5508/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:02.175   cmd[0]=browse_update_last50
14-10:49:02.177   cmd[0]=add_datadir_automatic
14-10:49:03.512   cmd[0]=efp
14-10:49:03.514   cmd[0]=isNmr
14-10:49:03.604   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:03.643   cmd[0]=browse_update_last50
14-10:49:03.644   cmd[0]=add_datadir_automatic
14-10:49:05.152   cmd[0]=ap
14-10:49:05.160   cmd[0]=xmac ap 
14-10:49:05.164   cmd[0]=_show_stat
14-10:49:05.193   cmd[0]=apks __WAITSTART__w__WAITEND__
14-10:49:05.193   cmd[0]=isNmr
14-10:49:05.195   to CPR: "apks" [id 19], COMMAND_WAIT_TERM
14-10:49:05.220   CPR task 19 resumed normally, termstat=0
14-10:49:05.221   cmd[0]=_show_stat
14-10:49:05.224   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:05.248   cmd[0]=browse_update_last50
14-10:49:05.249   cmd[0]=add_datadir_automatic
14-10:49:11.768   cmd[0]=bn
14-10:49:11.775   cmd[0]=xmac bn 
14-10:49:11.779   cmd[0]=_show_stat
14-10:49:11.814   cmd[0]=abs n __WAITSTART__w__WAITEND__
14-10:49:11.815   cmd[0]=isNmr
14-10:49:11.816   to CPR: "abs n" [id 20], COMMAND_WAIT_TERM
14-10:49:11.848   CPR task 20 resumed normally, termstat=0
14-10:49:11.849   cmd[0]=_show_stat
14-10:49:11.856   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:11.895   cmd[0]=browse_update_last50
14-10:49:11.896   cmd[0]=add_datadir_automatic
14-10:49:13.248   cmd[0]=.md
14-10:49:13.249   cmd[0]=isNmr
14-10:49:13.295   Entered MODULE de.bruker.nmr.pr.dual1d.Module
14-10:49:16.120   cmd[0]=re 1
14-10:49:16.134   cmd[0]=browse_update_last50
14-10:49:16.134   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:49:16.135   cmd[0]=add_datadir_automatic
14-10:49:18.776   cmd[0]=.ret
14-10:49:18.798   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:18.838   Finished MODULE de.bruker.nmr.pr.dual1d.Module
14-10:49:19.888   cmd[0]=re 1
14-10:49:19.901   cmd[0]=browse_update_last50
14-10:49:19.902   cmd[0]=add_datadir_automatic
14-10:49:19.905   cmd[0]=_create_drag_tranfer_handler
14-10:49:19.908   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:49:19.908   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:49:21.280   cmd[0]=bn
14-10:49:21.287   cmd[0]=xmac bn 
14-10:49:21.291   cmd[0]=_show_stat
14-10:49:21.315   cmd[0]=abs n __WAITSTART__w__WAITEND__
14-10:49:21.316   cmd[0]=isNmr
14-10:49:21.319   to CPR: "abs n" [id 21], COMMAND_WAIT_TERM
14-10:49:21.340   CPR task 21 resumed normally, termstat=0
14-10:49:21.340   cmd[0]=_show_stat
14-10:49:21.347   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/1/pdata/1
14-10:49:21.384   cmd[0]=browse_update_last50
14-10:49:21.386   cmd[0]=add_datadir_automatic
14-10:49:24.969   cmd[0]=re 3
14-10:49:24.982   cmd[0]=browse_update_last50
14-10:49:24.983   cmd[0]=add_datadir_automatic
14-10:49:24.984   cmd[0]=_create_drag_tranfer_handler
14-10:49:24.987   Entered MODULE de.bruker.nmr.pr.disp.BasicNMRModule
14-10:49:24.987   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:26.936   cmd[0]=.md
14-10:49:26.937   cmd[0]=isNmr
14-10:49:26.977   Entered MODULE de.bruker.nmr.pr.dual1d.Module
14-10:49:32.569   cmd[0]=sel_shift_lr
14-10:49:41.352   cmd[0]=.ret
14-10:49:41.370   adddataset corbaloc:iiop:127.0.0.1:5507/NMRDataServer##%/opt/nmrdata/data/nmrafd/nmr/z_atma_check_092026/3/pdata/1
14-10:49:41.406   Finished MODULE de.bruker.nmr.pr.dual1d.Module
14-10:49:44.848   cmd[0]=savelogs
14-10:52:18.872   cmd[0]=about
14-10:54:46.838   Trying to get BrukerDeviceIDXML from: http://149.236.99.253/BrukerDeviceId.xml
14-10:54:46.839   Trying to get BrukerDeviceIDXML from: http://149.236.99.20/BrukerDeviceId.xml
14-10:54:46.842   Trying to get BrukerDeviceIDXML from: http://149.236.99.252/BrukerDeviceId.xml
14-10:54:46.843   Trying to get BrukerDeviceIDXML from: http://149.236.99.20/BrukerDeviceId.xml
14-10:54:46.843   Trying to get BrukerDeviceIDXML from: http://149.236.99.90/BrukerDeviceId.xml
14-10:54:46.844   Trying to get BrukerDeviceIDXML from: http://149.236.99.55/BrukerDeviceId.xml
14-10:54:49.915   to CPR: "save_logs -verbose -name "TopSpinSupport_Leiden_600_ATMA_motor-error-problem_av600_nmrafd_2026-09-14T10.54" -add "/home/nmrafd/Pictures/atma_error_when_motor_is not_working|/home/nmrafd/Pictures/about_TS_ason20260914"" [id 22], COMMAND_WAIT_TERM
