2017-08-25T22:47:07.30918 INJ_TRANS LOAD REQUEST
2017-08-25T22:47:07.30972 INJ_TRANS RELOAD requested.  reloading system data...
2017-08-25T22:47:10.12136 INJ_TRANS module path: /opt/rtcds/userapps/release/cal/common/guardian/INJ_TRANS.py
2017-08-25T22:47:10.12139 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/__init__.py
2017-08-25T22:47:10.12139 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_det.py
2017-08-25T22:47:10.12140 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_io.py
2017-08-25T22:47:10.12140 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_types.py
2017-08-25T22:47:14.05150 INJ_TRANS RELOAD complete
2017-08-25T22:47:16.11558 INJ_TRANS [WAIT_FOR_NEXT_INJECT.run] ezca: H1:CAL-PINJX_TINJ_START => 1187736454.11
2017-08-25T22:47:16.11807 INJ_TRANS [WAIT_FOR_NEXT_INJECT.run] ezca: H1:CAL-PINJX_TINJ_OUTCOME => 0
2017-08-25T22:47:16.19433 INJ_TRANS EDGE: WAIT_FOR_NEXT_INJECT->CHECK_SCHEDULE_TIMES
2017-08-25T22:47:16.19488 INJ_TRANS calculating path: CHECK_SCHEDULE_TIMES->INJECT_SUCCESS
2017-08-25T22:47:16.19550 INJ_TRANS new target: CREATE_AWG_STREAM
2017-08-25T22:47:16.19812 INJ_TRANS executing state: CHECK_SCHEDULE_TIMES (35)
2017-08-25T22:47:16.19848 INJ_TRANS [CHECK_SCHEDULE_TIMES.enter]
2017-08-25T22:47:16.20311 INJ_TRANS [CHECK_SCHEDULE_TIMES.main] USERMSG 0: INJECTION IMMINENT: 1187736700.000000
2017-08-25T22:47:16.32059 INJ_TRANS EDGE: CHECK_SCHEDULE_TIMES->CREATE_AWG_STREAM
2017-08-25T22:47:16.32266 INJ_TRANS calculating path: CREATE_AWG_STREAM->INJECT_SUCCESS
2017-08-25T22:47:16.32325 INJ_TRANS new target: READ_WAVEFORM
2017-08-25T22:47:16.32399 INJ_TRANS executing state: CREATE_AWG_STREAM (50)
2017-08-25T22:47:16.32786 INJ_TRANS [CREATE_AWG_STREAM.enter]
2017-08-25T22:47:16.33302 INJ_TRANS [CREATE_AWG_STREAM.main] ezca: H1:CAL-PINJX_TINJ_TYPE => 3
2017-08-25T22:47:16.44859 INJ_TRANS EDGE: CREATE_AWG_STREAM->READ_WAVEFORM
2017-08-25T22:47:16.44907 INJ_TRANS calculating path: READ_WAVEFORM->INJECT_SUCCESS
2017-08-25T22:47:16.45125 INJ_TRANS new target: RAMP_GAIN_TO_1
2017-08-25T22:47:16.45194 INJ_TRANS executing state: READ_WAVEFORM (60)
2017-08-25T22:47:16.45220 INJ_TRANS [READ_WAVEFORM.enter]
2017-08-25T22:47:16.45459 INJ_TRANS [READ_WAVEFORM.main] Reading waveform data from /hwinj/Details/detchar/detchar_27July2017_PCAL_H1.txt
2017-08-25T22:47:45.19612 INJ_TRANS EDGE: READ_WAVEFORM->RAMP_GAIN_TO_1
2017-08-25T22:47:45.20740 INJ_TRANS calculating path: RAMP_GAIN_TO_1->INJECT_SUCCESS
2017-08-25T22:47:45.20760 INJ_TRANS new target: AWG_STREAM_OPEN_PREINJECT
2017-08-25T22:47:45.20772 INJ_TRANS executing state: RAMP_GAIN_TO_1 (65)
2017-08-25T22:47:45.20783 INJ_TRANS [RAMP_GAIN_TO_1.enter]
2017-08-25T22:47:45.22091 INJ_TRANS [RAMP_GAIN_TO_1.main] ezca: H1:CAL-PINJX_TRANSIENT_GAIN => 1.0
2017-08-25T22:47:47.42471 INJ_TRANS EDGE: RAMP_GAIN_TO_1->AWG_STREAM_OPEN_PREINJECT
2017-08-25T22:47:47.42475 INJ_TRANS calculating path: AWG_STREAM_OPEN_PREINJECT->INJECT_SUCCESS
2017-08-25T22:47:47.42476 INJ_TRANS new target: INJECT_BURST_ACTIVE
2017-08-25T22:47:47.42476 INJ_TRANS executing state: AWG_STREAM_OPEN_PREINJECT (70)
2017-08-25T22:47:47.42477 INJ_TRANS [AWG_STREAM_OPEN_PREINJECT.enter]
2017-08-25T22:47:47.42478 INJ_TRANS [AWG_STREAM_OPEN_PREINJECT.main] USERMSG 0: INJECTION IMMINENT: 1187736700.000000
2017-08-25T22:51:02.06938 INJ_TRANS JUMP target: INJECT_DETCHAR_ACTIVE
2017-08-25T22:51:02.06964 INJ_TRANS [AWG_STREAM_OPEN_PREINJECT.exit]
2017-08-25T22:51:02.13771 INJ_TRANS JUMP: AWG_STREAM_OPEN_PREINJECT->INJECT_DETCHAR_ACTIVE
2017-08-25T22:51:02.13830 INJ_TRANS calculating path: INJECT_DETCHAR_ACTIVE->INJECT_SUCCESS
2017-08-25T22:51:02.13878 INJ_TRANS new target: RAMP_GAIN_TO_0
2017-08-25T22:51:02.13953 INJ_TRANS executing state: INJECT_DETCHAR_ACTIVE (104)
2017-08-25T22:51:02.13998 INJ_TRANS [INJECT_DETCHAR_ACTIVE.enter]
2017-08-25T22:51:02.17166 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] USERMSG 0: INJECTION ACTIVE: 1187736700.000000
2017-08-25T22:51:02.58712 Aborting Logging because SIStrLog call failed -1
2017-08-25T22:52:23.83924 INJ_TRANS LOAD REQUEST
2017-08-25T22:52:23.83960 INJ_TRANS RELOAD requested.  reloading system data...
2017-08-25T22:52:25.98771 INJ_TRANS module path: /opt/rtcds/userapps/release/cal/common/guardian/INJ_TRANS.py
2017-08-25T22:52:25.98774 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/__init__.py
2017-08-25T22:52:25.98775 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_det.py
2017-08-25T22:52:25.98775 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_io.py
2017-08-25T22:52:25.98776 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_types.py
2017-08-25T22:52:30.54906 INJ_TRANS RELOAD complete
2017-08-25T22:53:11.50004 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] GPS start time: 1187736700.000000
2017-08-25T22:53:11.50017 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] Requested state: INJECT_DETCHAR_ACTIVE
2017-08-25T22:53:11.50029 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] Requested observation mode: 0
2017-08-25T22:53:11.50039 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] Scale factor: 1.000000
2017-08-25T22:53:11.50050 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] Waveform path: /hwinj/Details/detchar/detchar_27July2017_PCAL_{ifo}.txt
2017-08-25T22:53:11.57120 INJ_TRANS EDGE: INJECT_DETCHAR_ACTIVE->RAMP_GAIN_TO_0
2017-08-25T22:53:11.57164 INJ_TRANS calculating path: RAMP_GAIN_TO_0->INJECT_SUCCESS
2017-08-25T22:53:11.57213 INJ_TRANS new target: INJECT_SUCCESS
2017-08-25T22:53:11.57297 INJ_TRANS executing state: RAMP_GAIN_TO_0 (66)
2017-08-25T22:53:11.57323 INJ_TRANS [RAMP_GAIN_TO_0.enter]
2017-08-25T22:53:13.66507 INJ_TRANS [RAMP_GAIN_TO_0.main] ezca: H1:CAL-PINJX_TRANSIENT_GAIN => 0.0
2017-08-25T22:53:15.93886 INJ_TRANS EDGE: RAMP_GAIN_TO_0->INJECT_SUCCESS
2017-08-25T22:53:15.93890 INJ_TRANS calculating path: INJECT_SUCCESS->INJECT_SUCCESS
2017-08-25T22:53:15.93890 INJ_TRANS executing state: INJECT_SUCCESS (200)
2017-08-25T22:53:15.93989 INJ_TRANS [INJECT_SUCCESS.enter]
2017-08-25T22:53:17.96490 INJ_TRANS [INJECT_SUCCESS.main] ezca: H1:CAL-PINJX_TINJ_OUTCOME => 1
2017-08-25T22:53:17.96615 INJ_TRANS [INJECT_SUCCESS.main] ezca: H1:CAL-PINJX_TINJ_ENDED => 1187736815.96
2017-08-25T22:53:17.97210 INJ_TRANS JUMP target: WAIT_FOR_NEXT_INJECT
2017-08-25T22:53:17.97213 INJ_TRANS [INJECT_SUCCESS.exit]
2017-08-25T22:53:18.03834 INJ_TRANS JUMP: INJECT_SUCCESS->WAIT_FOR_NEXT_INJECT
2017-08-25T22:53:18.03871 INJ_TRANS calculating path: WAIT_FOR_NEXT_INJECT->INJECT_SUCCESS
2017-08-25T22:53:18.03935 INJ_TRANS new target: CHECK_SCHEDULE_TIMES
2017-08-25T22:53:18.04011 INJ_TRANS executing state: WAIT_FOR_NEXT_INJECT (20)
2017-08-25T22:53:18.04042 INJ_TRANS [WAIT_FOR_NEXT_INJECT.enter]
2017-08-25T22:53:52.02751 INJ_TRANS [WAIT_FOR_NEXT_INJECT.run] ezca: H1:CAL-PINJX_TINJ_START => 1187736850.03
2017-08-25T22:53:52.02862 INJ_TRANS [WAIT_FOR_NEXT_INJECT.run] ezca: H1:CAL-PINJX_TINJ_OUTCOME => 0
2017-08-25T22:53:52.14937 INJ_TRANS EDGE: WAIT_FOR_NEXT_INJECT->CHECK_SCHEDULE_TIMES
2017-08-25T22:53:52.14977 INJ_TRANS calculating path: CHECK_SCHEDULE_TIMES->INJECT_SUCCESS
2017-08-25T22:53:52.15674 INJ_TRANS new target: CREATE_AWG_STREAM
2017-08-25T22:53:52.15757 INJ_TRANS executing state: CHECK_SCHEDULE_TIMES (35)
2017-08-25T22:53:52.15783 INJ_TRANS [CHECK_SCHEDULE_TIMES.enter]
2017-08-25T22:53:52.15999 INJ_TRANS [CHECK_SCHEDULE_TIMES.main] USERMSG 0: INJECTION IMMINENT: 1187737150.000000
2017-08-25T22:53:52.27484 INJ_TRANS EDGE: CHECK_SCHEDULE_TIMES->CREATE_AWG_STREAM
2017-08-25T22:53:52.27523 INJ_TRANS calculating path: CREATE_AWG_STREAM->INJECT_SUCCESS
2017-08-25T22:53:52.27599 INJ_TRANS new target: READ_WAVEFORM
2017-08-25T22:53:52.27679 INJ_TRANS executing state: CREATE_AWG_STREAM (50)
2017-08-25T22:53:52.27716 INJ_TRANS [CREATE_AWG_STREAM.enter]
2017-08-25T22:53:52.40407 INJ_TRANS EDGE: CREATE_AWG_STREAM->READ_WAVEFORM
2017-08-25T22:53:52.40457 INJ_TRANS calculating path: READ_WAVEFORM->INJECT_SUCCESS
2017-08-25T22:53:52.40511 INJ_TRANS new target: RAMP_GAIN_TO_1
2017-08-25T22:53:52.40585 INJ_TRANS executing state: READ_WAVEFORM (60)
2017-08-25T22:53:52.40617 INJ_TRANS [READ_WAVEFORM.enter]
2017-08-25T22:53:52.40730 INJ_TRANS [READ_WAVEFORM.main] Reading waveform data from /hwinj/Details/detchar/detchar_27July2017_PCAL_H1.txt
2017-08-25T22:54:21.67873 INJ_TRANS EDGE: READ_WAVEFORM->RAMP_GAIN_TO_1
2017-08-25T22:54:21.67921 INJ_TRANS calculating path: RAMP_GAIN_TO_1->INJECT_SUCCESS
2017-08-25T22:54:21.68304 INJ_TRANS new target: AWG_STREAM_OPEN_PREINJECT
2017-08-25T22:54:21.68383 INJ_TRANS executing state: RAMP_GAIN_TO_1 (65)
2017-08-25T22:54:21.68411 INJ_TRANS [RAMP_GAIN_TO_1.enter]
2017-08-25T22:54:21.69513 INJ_TRANS [RAMP_GAIN_TO_1.main] ezca: H1:CAL-PINJX_TRANSIENT_GAIN => 1.0
2017-08-25T22:54:23.95574 INJ_TRANS EDGE: RAMP_GAIN_TO_1->AWG_STREAM_OPEN_PREINJECT
2017-08-25T22:54:23.95621 INJ_TRANS calculating path: AWG_STREAM_OPEN_PREINJECT->INJECT_SUCCESS
2017-08-25T22:54:23.95668 INJ_TRANS new target: INJECT_BURST_ACTIVE
2017-08-25T22:54:23.95746 INJ_TRANS executing state: AWG_STREAM_OPEN_PREINJECT (70)
2017-08-25T22:54:23.95771 INJ_TRANS [AWG_STREAM_OPEN_PREINJECT.enter]
2017-08-25T22:54:23.95854 INJ_TRANS [AWG_STREAM_OPEN_PREINJECT.main] USERMSG 0: INJECTION IMMINENT: 1187737150.000000
2017-08-25T22:56:52.01460 INJ_TRANS LOAD REQUEST
2017-08-25T22:56:52.01506 INJ_TRANS RELOAD requested.  reloading system data...
2017-08-25T22:56:54.17468 INJ_TRANS module path: /opt/rtcds/userapps/release/cal/common/guardian/INJ_TRANS.py
2017-08-25T22:56:54.17793 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/__init__.py
2017-08-25T22:56:54.17802 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_det.py
2017-08-25T22:56:54.17809 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_io.py
2017-08-25T22:56:54.17816 INJ_TRANS user code: /opt/rtcds/userapps/release/cal/common/guardian/injtools/inj_types.py
2017-08-25T22:56:58.18543 INJ_TRANS RELOAD complete
2017-08-25T22:58:32.08022 INJ_TRANS JUMP target: INJECT_DETCHAR_ACTIVE
2017-08-25T22:58:32.08060 INJ_TRANS [AWG_STREAM_OPEN_PREINJECT.exit]
2017-08-25T22:58:32.15069 INJ_TRANS JUMP: AWG_STREAM_OPEN_PREINJECT->INJECT_DETCHAR_ACTIVE
2017-08-25T22:58:32.15089 INJ_TRANS calculating path: INJECT_DETCHAR_ACTIVE->INJECT_SUCCESS
2017-08-25T22:58:32.15098 INJ_TRANS new target: RAMP_GAIN_TO_0
2017-08-25T22:58:32.15108 INJ_TRANS executing state: INJECT_DETCHAR_ACTIVE (104)
2017-08-25T22:58:32.15116 INJ_TRANS [INJECT_DETCHAR_ACTIVE.enter]
2017-08-25T22:58:32.15185 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] USERMSG 0: INJECTION ACTIVE: 1187737150.000000
2017-08-25T22:58:32.19608 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main]   File "/opt/rtcds/userapps/release/cal/common/guardian/INJ_TRANS.py", line 570, in main
2017-08-25T22:58:32.19610     self.hwinj.stream.send(self.hwinj.data)
2017-08-25T22:58:32.19611 
2017-08-25T22:58:32.19611 INJ_TRANS [INJECT_DETCHAR_ACTIVE.main] <type 'exceptions.AttributeError'> 'NoneType' object has no attribute 'send'
2017-08-25T22:58:32.21207 INJ_TRANS JUMP target: FAILURE_DURING_ACTIVE_INJECT
2017-08-25T22:58:32.21243 INJ_TRANS [INJECT_DETCHAR_ACTIVE.exit]
2017-08-25T22:58:32.28077 INJ_TRANS JUMP: INJECT_DETCHAR_ACTIVE->FAILURE_DURING_ACTIVE_INJECT
2017-08-25T22:58:32.28130 INJ_TRANS calculating path: FAILURE_DURING_ACTIVE_INJECT->INJECT_SUCCESS
2017-08-25T22:58:32.28189 INJ_TRANS new target: WAIT_FOR_NEXT_INJECT
2017-08-25T22:58:32.28268 INJ_TRANS executing state: FAILURE_DURING_ACTIVE_INJECT (300)
2017-08-25T22:58:32.28302 INJ_TRANS [FAILURE_DURING_ACTIVE_INJECT.enter]
2017-08-25T22:58:32.30664 INJ_TRANS [FAILURE_DURING_ACTIVE_INJECT.main] ezca: H1:CAL-PINJX_TRANSIENT_GAIN => 0.0
2017-08-25T22:58:34.44973 INJ_TRANS [FAILURE_DURING_ACTIVE_INJECT.main] ezca: H1:CAL-PINJX_TINJ_OUTCOME => -4
2017-08-25T22:58:34.45213 INJ_TRANS [FAILURE_DURING_ACTIVE_INJECT.main] ezca: H1:CAL-PINJX_TINJ_ENDED => 1187737132.45
2017-08-25T22:58:34.50029 INJ_TRANS [FAILURE_DURING_ACTIVE_INJECT.run] USERMSG 0: ERROR