LBC Tracking Tests 20141210

Details from the short period of sky testing on 20141210.

Summary
  • 1 crash in the new TCS code on a preset (but many others worked fine)
    this is likely due to the red/blue channels using the same result struct for IIF motion wait - added mutex around the result cleanup in the Ice code
  • no tracking or IDL crashes
  • tracking seemed fine - image data updated correctly too
  • guiding updates correctly sent to TCS
  • no focus corrections sent, couldn't find good pupils - but, I ran all the data through in standalone mode (see notes here for how to do that) and it gave the same results
  • notes and results saved in rm580f-1:/data/LBC-2.1-IA directory

Logging Updates Required

  • Not consistent in the logging between first and next. took out the junk too - delete messages, convert messages, etc.
  • not easy to grep for worked vs failed check for DONE with PROCESS - these are now consistent, with result, for both first and next.
  • delete the prescan message and "delete file"
  • consolidate - there are both "guiding error" and "guiding offset" messages that print the same info this is because the offset has a gain applied to it, but the gain is 1; I made the offset messages note that a gain is applied to differentiate
  • if first always sends 0.0,0.0 - don't print it printed in TCS code, leave it alone
  • send date to lbciaRun.log file
  • make sure the WARNING: 1 star only used for computation message gets into one of the other log files, not just the stdout like now


Log

01:55 We were on a target, but the first OB we did not have TCS connected.

01:59:17 The next PLAY

Error on the trajectory read from TCS - really should check the status here and not use it

 2014/12/10 01:59:49.813921 W B                   TCS GetRotatorTrajectory error: Timed out waiting for target OK [src/tcs/tcs.c:560] 

Why do we have zero in the offset values? Same with red at 02:00:56. This is always the case with track first.
2014/12/10 02:00:47.590814 N B TRACKERS IA       assignOutImageFirst: Selected 6 Good Sources in CHIP 1
2014/12/10 02:00:47.591074 N B TRACKERS          DONE with PROCESSFIRST result=0, lNStars1=6 B1.X=1741.9890 B1.Y=175.9660 FWHM1=7.3748 Background1=599.12 Threshold=10.0
2014/12/10 02:00:47.591106 N B TRACKERS          attempt #1 with 8.000 sec exposure found 6 stars on chip#1
2014/12/10 02:00:47.591122 N B TRACKERS          stellar pattern suitable for tracking of telescope and rotator
2014/12/10 02:00:47.591136 N B TRACKERS          TCS OffsetGuiding by (0.000000000000000deg,+0.000000000000000deg) start
2014/12/10 02:00:49.739138 N B TRACKERS          TCS OffsetGuiding completed

On this first PLAY command, red struggled and decreased the sex threshold before finding stars:
2014/12/10 02:00:54.958175 N R TRACKERS          sending IATrackingFirst C1: 0.000000,-3741.334200  -90.000000    offsets: 0.000,0.000,0.000
2014/12/10 02:00:54.958250 N R TRACKERS IA       IATrackingFirst pChips: confDIR=/lbccontrol/current/conf/  outfile2=track_first.out
2014/12/10 02:00:54.958354 N R TRACKERS IA       IATrackingFirst deleting files: /images/IA/track/r_imageRef1.cat /images/IA/track/r_reducedRef_1.cat
2014/12/10 02:00:54.958477 N R TRACKERS IA       IATrackingFirst for side:r  FileName1=/newdata/lbcrtec.20141210.020044_1.fits FileName2=/newdata/lbcrtec.20141210.020044_2.fits
2014/12/10 02:00:54.958503 N R TRACKERS IA       IATrackingFirst FileName1=/newdata/lbcrtec.20141210.020044_1.fits
2014/12/10 02:00:54.959078 N R TRACKERS IA       Converting /newdata/lbcrtec.20141210.020044_1.fits --> /images/IA/track/r_chip1.gif
2014/12/10 02:00:54.959121 N R TRACKERS IA       convert command: /lbt/lbc_runtime/bin/convert /newdata/lbcrtec.20141210.020044_1.fits -crop "2048x256+52+0" -normalize -equalize -resize "450x56" -equalize -rotate 90 /images/IA/track/r_chip1.gif
2014/12/10 02:00:55.308000 N R TRACKERS CCDCTRL  issuing >tftp -b32768 -o 192.168.38.32 put lbcrtec.20141210.020044_2.fits d:\lbcrtec.20141210.020044_2.fits > nul 2>&1< upload command completed in 0.1 sec
2014/12/10 02:00:55.308000 S R TRACKERS CCDCTRL  file upload queue contains 0 file/s
2014/12/10 02:00:55.765909 N R TRACKERS IA       Converting /newdata/lbcrtec.20141210.020044_2.fits --> /images/IA/track/r_chip2.gif
2014/12/10 02:00:56.577110 N R TRACKERS IA       assignOutImageFirst bestStars=0, counter=0   sextractor command = /lbt/lbc_runtime/bin/sex /newdata/lbcrtec.20141210.020044_1.fits -c /images/IA/track/r_default.sex
2014/12/10 02:00:56.642068 N R TRACKERS IA       assignOutImageFirst reading catalog file /images/IA/track/r_imageRef1.cat
2014/12/10 02:00:56.642177 N R TRACKERS IA          read star 0  1 2304.000000 46.888000 14.290000 18515.860000
2014/12/10 02:00:56.642236 N R TRACKERS IA          read star 1  2 51.000000 188.980000 6.880000 11581.270000
2014/12/10 02:00:56.642271 N R TRACKERS IA          read star 2  3 2304.000000 230.622000 12.490000 12893.290000
2014/12/10 02:00:56.642301 N R TRACKERS IA          read star 3  4 1105.746000 255.317000 8.180000 20392.510000
2014/12/10 02:00:56.642335 N R TRACKERS IA          read star 4  5 1098.493000 253.491000 5.950000 9488.897000
2014/12/10 02:00:56.642364 N R TRACKERS IA          read star 5  6 52.206000 252.411000 8.220000 12971.190000
2014/12/10 02:00:56.642394 N R TRACKERS IA          read star 6  7 52.668000 185.999000 4.320000 7588.630000
2014/12/10 02:00:56.642425 N R TRACKERS IA          read star 7  8 1667.532000 147.861000 12.770000 28181.210000
2014/12/10 02:00:56.642456 N R TRACKERS IA          read star 8  9 51.500000 129.583000 6.730000 11672.290000
2014/12/10 02:00:56.642497 N R TRACKERS IA       assignOutImageFirst, No Good Sources Found... decreasing SEX threshold (10.000 -> 3.5)
2014/12/10 02:00:56.642519 N R TRACKERS IA       assignOutImageFirst, Done with while loop, bestStarsNum=0, counter=1
2014/12/10 02:00:56.642653 N R TRACKERS IA       assignOutImageFirst bestStars=0, counter=1   sextractor command = /lbt/lbc_runtime/bin/sex /newdata/lbcrtec.20141210.020044_1.fits -c /images/IA/track/r_default.sex
2014/12/10 02:00:56.738703 N R TRACKERS IA       assignOutImageFirst reading catalog file /images/IA/track/r_imageRef1.cat
2014/12/10 02:00:56.738814 N R TRACKERS IA          read star 9  1 1108.510000 50.649000 10.410000 6703.913000
2014/12/10 02:00:56.738863 N R TRACKERS IA          read star 10  2 2304.000000 222.681000 19.820000 14037.200000
2014/12/10 02:00:56.738896 N R TRACKERS IA          read star 11  3 2304.000000 53.728000 22.050000 19267.190000
2014/12/10 02:00:56.738926 N R TRACKERS IA          read star 12  4 1127.095000 255.872000 13.770000 3202.084000
2014/12/10 02:00:56.738956 N R TRACKERS IA          read star 13  5 1110.438000 241.290000 10.880000 2426.820000
2014/12/10 02:00:56.738985 N R TRACKERS IA          read star 14  6 1117.664000 249.521000 18.020000 13577.990000
2014/12/10 02:00:56.739015 N R TRACKERS IA          read star 15  7 1113.999000 246.000000 9.140000 4389.210000
2014/12/10 02:00:56.739043 N R TRACKERS IA          read star 16  8 1102.409000 250.621000 13.820000 14216.920000
2014/12/10 02:00:56.739072 N R TRACKERS IA          read star 17  9 1105.746000 255.317000 11.590000 3638.983000
2014/12/10 02:00:56.739100 N R TRACKERS IA          read star 18  10 1111.911000 255.511000 14.090000 5598.543000
2014/12/10 02:00:56.739129 N R TRACKERS IA          read star 19  11 1098.340000 253.338000 5.770000 2248.557000
2014/12/10 02:00:56.739157 N R TRACKERS IA          read star 20  12 54.392000 250.496000 43.620000 41838.790000
2014/12/10 02:00:56.739185 N R TRACKERS IA          read star 21  13 52.408000 233.542000 13.370000 5506.863000
2014/12/10 02:00:56.739213 N R TRACKERS IA          read star 22  14 52.781000 213.783000 65.770000 88760.210000
2014/12/10 02:00:56.739258 N R TRACKERS IA          read star 23  15 53.343000 151.650000 17.420000 9123.607000
2014/12/10 02:00:56.739287 N R TRACKERS IA          read star 24  16 53.046000 79.084000 72.450000 107837.600000
2014/12/10 02:00:56.739315 N R TRACKERS IA          read star 25  17 52.822000 47.018000 67.020000 69647.680000
2014/12/10 02:00:56.739342 N R TRACKERS IA          read star 26  18 54.797000 142.400000 60.430000 64533.400000
2014/12/10 02:00:56.739370 N R TRACKERS IA          read star 27  19 53.183000 125.403000 29.450000 27337.920000
2014/12/10 02:00:56.739401 N R TRACKERS IA          read star 28  20 52.470000 106.398000 18.940000 11443.150000
2014/12/10 02:00:56.739438 N R TRACKERS IA          read star 29  21 52.687000 31.757000 26.110000 13644.230000
2014/12/10 02:00:56.739464 N R TRACKERS IA          read star 30  22 51.756000 19.231000 24.810000 15217.600000
2014/12/10 02:00:56.739489 N R TRACKERS IA          read star 31  23 52.196000 190.677000 18.500000 10825.410000
2014/12/10 02:00:56.739515 N R TRACKERS IA          read star 32  24 52.407000 174.689000 25.330000 17841.950000
2014/12/10 02:00:56.739545 N R TRACKERS IA          read star 33  25 1677.952000 148.680000 5.770000 1048.390000
2014/12/10 02:00:56.739573 N R TRACKERS IA          read star 34  26 1668.528000 146.284000 16.880000 33464.570000
2014/12/10 02:00:56.739602 N R TRACKERS IA          read star 35  27 1661.897000 129.023000 7.990000 7155.414000
2014/12/10 02:00:56.739659 N R TRACKERS IA       assignOutImageFirst, No Good Sources Found... decreasing SEX threshold (3.500 -> 3.5)
2014/12/10 02:00:56.739680 N R TRACKERS IA       assignOutImageFirst, Done with while loop, bestStarsNum=1, counter=2
2014/12/10 02:00:56.739695 N R TRACKERS IA       assignOutImageFirst: Selected 1 Good Sources in CHIP 1
2014/12/10 02:00:56.739936 N R TRACKERS          DONE with PROCESSFIRST result=0, lNStars1=1 B1.X=1661.8970 B1.Y=129.0230 FWHM1=3.0119 Background1=296.92 Threshold=3.5
2014/12/10 02:00:56.739970 N R TRACKERS          attempt #1 with 8.000 sec exposure found 1 stars on chip#1
2014/12/10 02:00:56.739987 N R TRACKERS          stellar pattern suitable for tracking of telescope and rotator
2014/12/10 02:00:56.740001 N R TRACKERS          TCS OffsetGuiding by (0.000000000000000deg,+0.000000000000000deg) start
 

When it got to NEXT, blue failed. Does it try again (continue?) should this be an error? I deleted the can not process image message, it's redundant.
2014/12/10 02:01:01.136761 N B TRACKERS          sending IATrackingNext 910,1235  1,256
2014/12/10 02:01:01.136807 N B TRACKERS          CALLING NEXT with files: /newdata/lbcbtec.20141210.020050_1.fits and /newdata/lbcbtec.20141210.020050_2.fits
2014/12/10 02:01:01.136831 N B TRACKERS          sending IATrackingNext C1: 0.000000,-3741.334200  -90.000000    offsets: 0.000,0.000,0.000
2014/12/10 02:01:01.092000 N B TRACKERS CCDCTRL  issuing >tftp -b32768 -o 192.168.38.32 put lbcbtec.20141210.020050_2.fits d:\lbcbtec.20141210.020050_2.fits > nul 2>&1< upload command ...
2014/12/10 02:01:01.136978 N B TRACKERS IA       IATrackingNext delete file: /images/IA/track/b_reduced_1.cat
2014/12/10 02:01:01.137016 N B TRACKERS IA       IATrackingNext, side:b szFileName1=/newdata/lbcbtec.20141210.020050_1.fits, catfile=/images/IA/track/b_image1.cat, reducedcatfile=/images/IA/track/b_reduced_1.cat, region:910,1235 1,256
2014/12/10 02:01:01.137506 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.020050_1.fits --> /images/IA/track/b_chip1.gif
2014/12/10 02:01:01.137550 N B TRACKERS IA       convert command: /lbt/lbc_runtime/bin/convert /newdata/lbcbtec.20141210.020050_1.fits -crop "2048x256+52+0" -normalize -equalize -resize "450x56" -equalize -rotate 90 /images/IA/track/b_chip1.gif
2014/12/10 02:01:01.154000 N B TRACKERS CCDCTRL  issuing >tftp -b32768 -o 192.168.38.32 put lbcbtec.20141210.020050_2.fits d:\lbcbtec.20141210.020050_2.fits > nul 2>&1< upload command completed in 0.0 sec
2014/12/10 02:01:01.154000 S B TRACKERS CCDCTRL  file upload queue contains 0 file/s
2014/12/10 02:01:01.924122 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.020050_2.fits --> /images/IA/track/b_chip2.gif
2014/12/10 02:01:02.281705 N B TRACKERS IA       getPrescan iprescanDim=50, iscanDim=2048, ioverscanDim=206
2014/12/10 02:01:02.281804 N B TRACKERS IA       TrackNext sextractor command = /lbt/lbc_runtime/bin/sex /newdata/lbcbtec.20141210.020050_1.fits -c /images/IA/track/b_assoc1.sex
2014/12/10 02:01:02.349167 N B TRACKERS IA       TrackNext reading catalog file /images/IA/track/b_image1.cat
2014/12/10 02:01:02.349230 W B TRACKERS IA       No Good Sources Found for chip 1, verify image data or change Sextractor configuration files.
2014/12/10 02:01:02.356726 N B TRACKERS IA       /images/IA/track/b_guide.fits ---> /images/IA/track/b_guide.gif
2014/12/10 02:01:02.369724 E B TRACKERS IA       assignOutImageNext: can not process image
2014/12/10 02:01:02.369741 W B TRACKERS          IATrackingNext failed: 1, continue

Red failed as well. Then John decided to do some focus. We maybe are not checking the STOP command before calling active optics:

2014/12/10 02:01:29.533637 N -                   received >STOP< command from 192.168.58.33 address
2014/12/10 02:01:29.533748 N -                   executing STOP command ...
2014/12/10 02:01:29.533825 N -                   Stop signal to IIF functions
2014/12/10 02:01:29.533907 N B                   >>> stop start
2014/12/10 02:01:29.534000 N R                   >>> stop start
2014/12/10 02:01:29.534099 N B CAMERA            >>> stop start
2014/12/10 02:01:29.534184 N R CAMERA            >>> stop start
2014/12/10 02:01:29.534338 N B TRACKERS          >>> stop start
2014/12/10 02:01:29.534528 N R TRACKERS          >>> stop start
2014/12/10 02:01:29.821000 N R TRACKERS CCDCTRL  stop ...
...
2014/12/10 02:01:29.572000 N R CAMERA   CCDCTRL  stop ...
2014/12/10 02:01:29.539086 N R TRACKERS          CALLING ActiveOptics with channel r, first=1, exp=100.000000
2014/12/10 02:01:29.539141 N R TRACKERS IA       IDL command string is >DoTecIA, 1, 'r', 100.00, datadir='/newdata'<
...
2014/12/10 02:01:29.539563 N B TRACKERS          CALLING ActiveOptics with channel b, first=1, exp=100.000000
2014/12/10 02:01:29.539639 N B TRACKERS IA       IDL command string is >DoTecIA, 1, 'b', 100.00, datadir='/newdata'<
2014/12/10 02:01:29.572000 W R CAMERA   CCDCTRL  safetyoff ...
...
2014/12/10 02:01:29.563700 W B                   channel STOP found [src/channel/channel.c:1229]
2014/12/10 02:01:29.852000 W R TRACKERS CCDCTRL  safetyoff completed
2014/12/10 02:01:29.529000 W B TRACKERS CCDCTRL  safetyoff completed
2014/12/10 02:01:29.529000 N B TRACKERS CCDCTRL  stop completed
2014/12/10 02:01:29.852000 N R TRACKERS CCDCTRL  stop completed
2014/12/10 02:01:29.573184 N R                   expose completed
2014/12/10 02:01:29.573235 W R                   channel STOP found [src/channel/channel.c:1229]
2014/12/10 02:01:29.619000 W R CAMERA   CCDCTRL  safetyoff completed
2014/12/10 02:01:29.619000 N R CAMERA   CCDCTRL  stop completed
2014/12/10 02:01:29.610102 N R CAMERA            >>> stop completed
2014/12/10 02:01:29.610163 N R CAMERA            >>> resume start
...
2014/12/10 02:01:29.697000 N R CAMERA   CCDCTRL  resume completed
2014/12/10 02:01:29.670575 N R CAMERA            >>> resume completed
2014/12/10 02:01:29.674388 N R                   completed 0 OB image/s
2014/12/10 02:01:29.674425 N R                   OB execution step completed
...
2014/12/10 02:01:31.028000 N B CAMERA   CCDCTRL  stop completed
2014/12/10 02:01:30.693452 N B CAMERA            >>> stop completed
2014/12/10 02:01:30.693483 N B CAMERA            >>> resume start
...
2014/12/10 02:01:31.122000 N B CAMERA   CCDCTRL  resume completed
2014/12/10 02:01:30.783113 N B CAMERA            >>> resume completed
2014/12/10 02:01:30.843294 N R TRACKERS IA       Done with IDL code...
2014/12/10 02:01:30.843309 N R TRACKERS IA       Reading LBCFPIA results from /images/IA/FPIA/lbciaCoeffs_red.dat
2014/12/10 02:01:30.843380 N R TRACKERS IA       Copying LBCFPIA results to /images/IA/FPIA/AOParam_red.txt
....
We were not checking the stop flag in the old code either - I modified this to do so.


02:02:04 DOFPIA failed because of TCS 64-bit?? John wrote this:

Running DOFPIA as lbto@obs2

terminate called after throwing an instance of 'std::domain_error'
  what():  The parameter EVENT_FILE of type string was not found.
Abort (core dumped)
Exit 134

See FOUR core files in /home/lbto

at ./tcs/core/ParamMap.hpp:356
....
#23 0x00e99f05 in lbto::Configuration::doGetParameter<std::basic_string<char, std::char_traits<char>, std::allocator<char> > > (this=0x11d8c40, Name="EVENT_FILE")
    at ./tcs/core/Configuration.hpp:285
#24 0x00f4762f in lbto::Configuration::doGetPath (this=0x11d8c40, Tag="EVENT_FILE", FileName="") at tcs/core/Configuration.cpp:419
#25 0x00f47580 in lbto::Configuration::getPath (Tag="EVENT_FILE", FileName="") at tcs/core/Configuration.cpp:408
#26 0x00f1ee69 in lbto::Process::init (this=0x8c31028) at tcs/core/Process.cpp:62
#27 0x00f1ec95 in lbto::Process::Process (this=0x8c31028) at tcs/core/Process.cpp:40
#28 0x00f1fa7d in __static_initialization_and_destruction_0 (__initialize_p=1, __priority=65535) at tcs/core/Process.cpp:33
#29 0x00f1faf1 in global constructors keyed to Process.cpp(void) () at tcs/core/Process.cpp:142
#30 0x0109604d in __do_global_ctors_aux () from /lbt/tcs/2014A/tcs/lib/libTCS.so
#31 0x00e37d34 in _init () from /lbt/tcs/2014A/tcs/lib/libTCS.so
#32 0x0043900f in _dl_init_internal () from /lib/ld-linux.so.2
#33 0x0042a88f in _dl_start_user () from /lib/ld-linux.so.2

This was because they had "stubbed out TCS" on the obs computers that it would run GUIs on the 64bit machines. So, it did not find the config file.

This problem will go away if I use the TCSSendWavefront built with the Ice interface.



02:04:58 switching back to standard TCS build.

02:15:22 restart LBC after build switched.

02:20:49 PLAY WT10_276.

02:23:34 PLAY RB_rVfastextra.

02:25:03 PLAY RB_rVfastextra.

02:26:40 PLAY RB_rVfastextra.

02:28:47 PLAY WT10_276. Crashed in new TCS code on the preset. Last thing in the log is:
2014/12/10 02:28:51.499544 N B CAMERA            >>> preset completed

#0  0x0073b424 in __kernel_vsyscall ()
#1  0x00280b11 in raise () from /lib/libc.so.6
#2  0x002823ea in abort () from /lib/libc.so.6
#3  0x002c09d5 in __libc_message () from /lib/libc.so.6
#4  0x002c6e31 in malloc_printerr () from /lib/libc.so.6
#5  0x0808c11a in Result_destroy (res=0x831ec10) at src/tcs/ICE_ANSI-C_bridge.cpp:1261
#6  0x0808bffb in fillResultStruct (sres=..., res=...) at src/tcs/ICE_ANSI-C_bridge.cpp:1223
#7  0x0808d1fd in AMI_IIFServer_PresetTelescopeI::ice_response (this=0xa9c009d8, res=...) at src/tcs/ICE_ANSI-C_bridge.cpp:198
#8  0x080fbf3b in lbto::AMI_IIFServer_PresetTelescope::__response (this=0xa9c009d8, __ret=...) at ./IIFServer.h:1479
#9  0x08134297 in lbto::CallbackNC_IIFServer_PresetTelescope<lbto::AMI_IIFServer_PresetTelescope>::__completed (this=0xa9c06838, __result=...) at ./IIFServer.h:9403
#10 0x01625f0d in Ice::AsyncResult::__response (this=0xa9c06874) at OutgoingAsync.cpp:314
#11 0x016285cd in IceInternal::OutgoingAsync::__finished (this=0xa9c06870, is=...) at OutgoingAsync.cpp:731
#12 0x01589993 in Ice::ConnectionI::dispatch (this=0xb1e017e8, startCB=..., sentCBs=std::vector of length 0, capacity 0, compress=0 '\000', requestId=4113, 
    invokeNum=0, servantManager=..., adapter=..., outAsync=..., stream=...) at ConnectionI.cpp:1469
#13 0x0158973e in Ice::ConnectionI::message (this=0xb1e017e8, current=...) at ConnectionI.cpp:1437
#14 0x016a3abc in IceInternal::ThreadPool::run (this=0xb7307720, thread=...) at ThreadPool.cpp:624
#15 0x016a54af in IceInternal::ThreadPool::EventHandlerThread::run (this=0xb73085a0) at ThreadPool.cpp:1097
#16 0x006a8baa in startHook (arg=0xb73085a0) at Thread.cpp:413
#17 0x00217b39 in start_thread () from /lib/libpthread.so.0
#18 0x00338d6e in clone () from /lib/libc.so.6

02:31:50 PLAY WT10_276, 100 sec exposure

 2014/12/10 02:32:39.380846 N B TRACKERS          sending IATrackingFirst C1: 0.000000,-3741.334200  -90.000000    offsets: 0.000,0.000,0.000
2014/12/10 02:32:39.380942 N B TRACKERS IA       IATrackingFirst pChips: confDIR=/lbccontrol/current/conf/  outfile2=track_first.out
2014/12/10 02:32:39.333000 N B TRACKERS CCDCTRL  issuing >tftp -b32768 -o 192.168.38.32 put lbcbtec.20141210.023228_2.fits d:\lbcbtec.20141210.023228_2.fits > nul 2>&1< upload command ...
2014/12/10 02:32:39.381224 N B TRACKERS IA       IATrackingFirst for side:b  FileName1=/newdata/lbcbtec.20141210.023228_1.fits FileName2=/newdata/lbcbtec.20141210.023228_2.fits
2014/12/10 02:32:39.381250 N B TRACKERS IA       IATrackingFirst FileName1=/newdata/lbcbtec.20141210.023228_1.fits
2014/12/10 02:32:39.395558 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.023228_1.fits --> /images/IA/track/b_chip1.gif
2014/12/10 02:32:39.395602 N B TRACKERS IA       convert command: /lbt/lbc_runtime/bin/convert /newdata/lbcbtec.20141210.023228_1.fits -crop "2048x256+52+0" -normalize -equalize -resize "450x56" -equalize -rotate 90 /images/IA/track/b_chip1.gif
..
2014/12/10 02:32:41.595409 N B TRACKERS IA       assignOutImageFirst reading catalog file /images/IA/track/b_imageRef1.cat
2014/12/10 02:32:41.595491 N B TRACKERS IA          read star 0  1 1338.401000 72.723000 5.390000 7116.089000
2014/12/10 02:32:41.595528 N B TRACKERS IA          read star 1  2 2304.000000 128.020000 27.730000 53292.640000
2014/12/10 02:32:41.595548 N B TRACKERS IA          read star 2  3 52.448000 20.365000 28.280000 159359.500000
2014/12/10 02:32:41.595566 N B TRACKERS IA          read star 3  4 52.592000 101.234000 26.940000 196031.300000
2014/12/10 02:32:41.595583 N B TRACKERS IA          read star 4  5 52.535000 131.772000 22.500000 113826.500000
2014/12/10 02:32:41.595601 N B TRACKERS IA          read star 5  6 52.760000 248.364000 25.070000 86148.040000
2014/12/10 02:32:41.595618 N B TRACKERS IA          read star 6  7 52.382000 156.429000 25.940000 161135.400000
2014/12/10 02:32:41.595635 N B TRACKERS IA          read star 7  8 52.147000 210.771000 37.710000 276288.400000
2014/12/10 02:32:41.595653 N B TRACKERS IA          read star 8  9 1896.051000 179.019000 5.380000 26316.760000
2014/12/10 02:32:41.595672 N B TRACKERS IA       assignOutImageFirst, Done with while loop, bestStarsNum=2, counter=0
2014/12/10 02:32:41.595680 N B TRACKERS IA       assignOutImageFirst: Selected 2 Good Sources in CHIP 1
2014/12/10 02:32:41.595862 N B TRACKERS          DONE with PROCESSFIRST result=0, lNStars1=2 B1.X=1896.0510 B1.Y=179.0190 FWHM1=3.5137 Background1=471.17 Threshold=10.0
2014/12/10 02:32:41.595879 N B TRACKERS          attempt #1 with 8.000 sec exposure found 2 stars on chip#1
2014/12/10 02:32:41.595888 N B TRACKERS          stellar pattern suitable for tracking of telescope and rotator
2014/12/10 02:32:41.595895 N B TRACKERS          TCS OffsetGuiding by (0.000000000000000deg,+0.000000000000000deg) start

NEXT:

2014/12/10 02:32:55.941677 N B TRACKERS          sending IATrackingNext 910,1235  1,256
2014/12/10 02:32:55.941726 N B TRACKERS          CALLING NEXT with files: /newdata/lbcbtec.20141210.023245_1.fits and /newdata/lbcbtec.20141210.023245_2.fits
2014/12/10 02:32:55.941792 N B TRACKERS          sending IATrackingNext C1: 0.000000,-3741.334200  -90.000000    offsets: 0.000,0.000,0.000
2014/12/10 02:32:55.941955 N B TRACKERS IA       IATrackingNext, side:b szFileName1=/newdata/lbcbtec.20141210.023245_1.fits, catfile=/images/IA/track/b_image1.cat, reducedcatfile=/images/IA/track/b_reduced_1.cat, region:910,1235 1,256
2014/12/10 02:32:55.942503 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.023245_1.fits --> /images/IA/track/b_chip1.gif
2014/12/10 02:32:55.942551 N B TRACKERS IA       convert command: /lbt/lbc_runtime/bin/convert /newdata/lbcbtec.20141210.023245_1.fits -crop "2048x256+52+0" -normalize -equalize -resize "450x56" -equalize -rotate 90 /images/IA/track/b_chip1.gif
2014/12/10 02:32:56.783094 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.023245_2.fits --> /images/IA/track/b_chip2.gif
2014/12/10 02:32:57.517274 N B TRACKERS IA       TrackNext sextractor command = /lbt/lbc_runtime/bin/sex /newdata/lbcbtec.20141210.023245_1.fits -c /images/IA/track/b_assoc1.sex
2014/12/10 02:32:57.634512 N B TRACKERS IA       TrackNext reading catalog file /images/IA/track/b_image1.cat
2014/12/10 02:32:57.634607 N B TRACKERS IA          read star 1  1 1896.077000 179.637000 5.290000 29549.340000
2014/12/10 02:32:57.634678 N B TRACKERS IA          read star 2  2 1338.350000 73.107000 5.440000 8863.028000
2014/12/10 02:32:57.634723 N B TRACKERS IA       Selected 2 Good Sources in CHIP 1
2014/12/10 02:32:57.634827 N B TRACKERS IA       Found a Guide Source with (5.000 Threshold): XCENTR = 1896.051000  YCENTR = 179.019000
2014/12/10 02:32:57.648000 N B TRACKERS IA       /images/IA/track/b_guide.fits ---> /images/IA/track/b_guide.gif
2014/12/10 02:32:57.670118 S B TRACKERS          IATrackingNext returned 2 stars, 719.37 background, -0.5015 -0.0123 0.0000 offsets; pixscale=0.000063
2014/12/10 02:32:57.670158 S B TRACKERS          estimated guiding offsets in FP coords are -0.11,-0.00 arcsec and 0.000 deg of rotation
2014/12/10 02:32:57.670377 N B TRACKERS          guiding error in AZ-El of -0.10,-0.05 arcsec has been detected
2014/12/10 02:32:57.670398 N B TRACKERS          guiding offset in AZ-El of -0.10,-0.05 arcsec has been applied
2014/12/10 02:32:57.670411 N B TRACKERS          TCS OffsetGuiding by (-0.000028781959027deg,-0.000012790821832deg) start
  

Red did OK too:

2014/12/10 02:33:01.083663 S R TRACKERS          IATrackingNext returned 5 stars, 816.62 background, -0.8371 0.3555 0.0000 offsets; pixscale=0.000063
2014/12/10 02:33:01.083680 S R TRACKERS          estimated guiding offsets in FP coords are -0.19,0.08 arcsec and 0.000 deg of rotation
2014/12/10 02:33:01.083892 N R TRACKERS          guiding error in AZ-El of -0.14,-0.15 arcsec has been detected
2014/12/10 02:33:01.083902 N R TRACKERS          guiding offset in AZ-El of -0.14,-0.15 arcsec has been applied
2014/12/10 02:33:01.083913 N R TRACKERS          TCS OffsetGuiding by (-0.000037904164306deg,-0.000042697293058deg) start

All offsets red and then blue, paired with the TCS output:

2014/12/10 02:33:01.083913 N R TRACKERS          TCS OffsetGuiding by (-0.000037904164306deg,-0.000042697293058deg) start
2014/12/10 02:33:17.885263 N R TRACKERS          TCS OffsetGuiding by (-0.000061217461842deg,-0.000093500117654deg) start
2014/12/10 02:33:33.901342 N R TRACKERS          TCS OffsetGuiding by (0.000004885491790deg,-0.000037175724136deg) start
2014/12/10 02:33:52.074764 N R TRACKERS          TCS OffsetGuiding by (0.000003902308041deg,-0.000060001186568deg) start
2014/12/10 02:38:23.804284 N R TRACKERS          TCS OffsetGuiding by (-0.000014960869698deg,-0.000045337269819deg) start
2014/12/10 02:38:40.285784 N R TRACKERS          TCS OffsetGuiding by (-0.000036152587870deg,-0.000006180715590deg) start
2014/12/10 02:40:27.504912 N R TRACKERS          TCS OffsetGuiding by (-0.000025262926144deg,-0.000005719869595deg) start
2014/12/10 02:40:43.899349 N R TRACKERS          TCS OffsetGuiding by (0.000046458540425deg,-0.000099375044635deg) start
2014/12/10 02:42:33.373331 N R TRACKERS          TCS OffsetGuiding by (0.000042805823616deg,-0.000007595784278deg) start
2014/12/10 02:42:54.560122 N R TRACKERS          TCS OffsetGuiding by (-0.000016297051162deg,-0.000062396596577deg) start
Wed Dec 10 02:00:56.439 2014 57001.08398657023 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:32:42.064 2014 57001.10604241887 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:01.074 2014 57001.10626243653 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 -6.615524673644417e-07, coord2 -7.452083455462800e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:17.877 2014 57001.10645691059 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 -1.068446268857902e-06, coord2 -1.631884904063146e-06, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:33.892 2014 57001.10664227181 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 8.526791730793999e-08, coord2 -6.488387879849231e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:52.065 2014 57001.10685261080 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 6.810812374880267e-08, coord2 -1.047218260719442e-06, rotation angle 0.000000000000000e+00
Wed Dec 10 02:38:08.010 2014 57001.10981493170 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:38:23.793 2014 57001.10999760546 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 -2.611164351842634e-07, coord2 -7.912846322044439e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:38:40.274 2014 57001.11018836158 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 -6.309816914404262e-07, coord2 -1.078738371811335e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:40:12.394 2014 57001.11125456249 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:40:27.493 2014 57001.11142931911 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 -4.409212398939693e-07, coord2 -9.983055721105933e-08, rotation angle 0.000000000000000e+00
Wed Dec 10 02:40:43.887 2014 57001.11161906935 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 8.108544960880141e-07, coord2 -1.734421723200525e-06, rotation angle 0.000000000000000e+00
Wed Dec 10 02:42:18.245 2014 57001.11271117540 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:42:33.360 2014 57001.11288612054 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 7.471025611311540e-07, coord2 -1.325714449171833e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:42:54.548 2014 57001.11313134614 5 IIF  iif.command.guide.started right Guide command started for LBC, coord1 -2.844372011426457e-07, coord2 -1.089026052306506e-06, rotation angle 0.000000000000000e+00


2014/12/10 02:32:57.670411 N B TRACKERS          TCS OffsetGuiding by (-0.000028781959027deg,-0.000012790821832deg) start
2014/12/10 02:33:14.419600 N B TRACKERS          TCS OffsetGuiding by (-0.000045880168982deg,-0.000063413258544deg) start
2014/12/10 02:33:31.399759 N B TRACKERS          TCS OffsetGuiding by (0.000025407733831deg,-0.000028763059873deg) start
2014/12/10 02:33:49.393140 N B TRACKERS          TCS OffsetGuiding by (0.000031972869641deg,-0.000026709742463deg) start
2014/12/10 02:34:07.020835 N B TRACKERS          TCS OffsetGuiding by (-0.000015331480554deg,-0.000041768834627deg) start
2014/12/10 02:38:23.294629 N B TRACKERS          TCS OffsetGuiding by (0.000000515021823deg,-0.000012370958059deg) start
2014/12/10 02:38:42.472351 N B TRACKERS          TCS OffsetGuiding by (0.000037477885761deg,-0.000079481680322deg) start
2014/12/10 02:40:38.469778 N B TRACKERS          TCS OffsetGuiding by (0.000016429927152deg,-0.000074951400193deg) start
2014/12/10 02:42:47.701650 N B TRACKERS          TCS OffsetGuiding by (-0.000009283400117deg,-0.000031956787129deg) start
2014/12/10 02:43:07.156092 N B TRACKERS          TCS OffsetGuiding by (-0.000057889447234deg,-0.000066803571520deg) start
Wed Dec 10 02:00:47.290 2014 57001.08388067668 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:32:41.587 2014 57001.10603689033 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:32:57.661 2014 57001.10622293434 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 -5.023399501890742e-07, coord2 -2.232419550024238e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:14.410 2014 57001.10641679266 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 -8.007600101158741e-07, coord2 -1.106770151017571e-06, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:31.390 2014 57001.10661331620 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 4.434486108174331e-07, coord2 -5.020100977298930e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:33:49.385 2014 57001.10682158653 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 5.580318465437876e-07, coord2 -4.661729483356155e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:34:07.010 2014 57001.10702558852 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 -2.675848148692178e-07, coord2 -7.290036889552854e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:38:07.746 2014 57001.10981188082 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:38:23.284 2014 57001.10999172027 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 8.988826530818033e-09, coord2 -2.159139497549779e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:38:42.462 2014 57001.11021368104 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 6.541125032093513e-07, coord2 -1.387217016631201e-06, rotation angle 0.000000000000000e+00
Wed Dec 10 02:40:22.611 2014 57001.11137282337 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:40:38.458 2014 57001.11155623294 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 2.867563246701778e-07, coord2 -1.308148712348941e-06, rotation angle 0.000000000000000e+00
Wed Dec 10 02:42:32.387 2014 57001.11287486046 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 0.000000000000000e+00, coord2 0.000000000000000e+00, rotation angle 0.000000000000000e+00
Wed Dec 10 02:42:47.689 2014 57001.11305196113 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 -1.620258978270210e-07, coord2 -5.577511537672053e-07, rotation angle 0.000000000000000e+00
Wed Dec 10 02:43:07.143 2014 57001.11327712366 5 IIF  iif.command.guide.started left Guide command started for LBC, coord1 -1.010361456390346e-06, coord2 -1.165942275115951e-06, rotation angle 0.000000000000000e+00




02:37:27 PLAY fast OB with multiple exposures so we can see some focus corrections.
2014/12/10 01:58:51.926871 N B TRACKERS IA       IDL command string is >DoTecIA, 1, 'b', 100.00, datadir='/newdata'<
2014/12/10 01:58:52.075639 N R TRACKERS IA       IDL command string is >DoTecIA, 1, 'r', 100.00, datadir='/newdata'<
2014/12/10 01:58:53.159864 N B TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 01:58:54.253610 N R TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:01:29.539141 N R TRACKERS IA       IDL command string is >DoTecIA, 1, 'r', 100.00, datadir='/newdata'<
2014/12/10 02:01:29.539639 N B TRACKERS IA       IDL command string is >DoTecIA, 1, 'b', 100.00, datadir='/newdata'<
2014/12/10 02:01:30.843449 N R TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:01:31.823942 N B TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:34:05.130689 N R TRACKERS IA       IDL command string is >DoTecIA, 1, 'r', 100.00, datadir='/newdata'<
2014/12/10 02:34:07.132225 N R TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:34:10.831105 N B TRACKERS IA       IDL command string is >DoTecIA, 1, 'b', 100.00, datadir='/newdata'<
2014/12/10 02:34:11.842972 N B TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:38:50.376158 N R TRACKERS IA       IDL command string is >DoTecIA, 1, 'r', 60.00, datadir='/newdata'<
2014/12/10 02:38:51.403751 N R TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:38:55.489248 N B TRACKERS IA       IDL command string is >DoTecIA, 1, 'b', 60.00, datadir='/newdata'<
2014/12/10 02:38:56.681020 N B TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:40:55.540724 N R TRACKERS IA       IDL command string is >DoTecIA, 0, 'r', 60.00, datadir='/newdata'<
2014/12/10 02:40:56.931466 N R TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:41:10.275441 N B TRACKERS IA       IDL command string is >DoTecIA, 0, 'b', 60.00, datadir='/newdata'<
2014/12/10 02:41:11.309018 N B TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:43:00.657894 N R TRACKERS IA       IDL command string is >DoTecIA, 0, 'r', 60.00, datadir='/newdata'<
2014/12/10 02:43:01.857190 N R TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <
2014/12/10 02:43:20.602524 N B TRACKERS IA       IDL command string is >DoTecIA, 0, 'b', 60.00, datadir='/newdata'<
2014/12/10 02:43:21.766069 N B TRACKERS IA       AO processing completed with retcode:0 >Computing ref aberrations, no corrections required <


2014/12/10 02:38:21.269201 N B TRACKERS          CALLING NEXT with files: /newdata/lbcbtec.20141210.023810_1.fits and /newdata/lbcbtec.20141210.023810_2.fits
2014/12/10 02:38:21.269512 N B TRACKERS IA       IATrackingNext, side:b szFileName1=/newdata/lbcbtec.20141210.023810_1.fits, catfile=/images/IA/track/b_image1.cat, reducedcatfile=/images/IA/track/b_reduced_1.cat, region:910,1235 1,256
2014/12/10 02:38:23.294392 S B TRACKERS          IATrackingNext returned 1 stars, 763.36 background, -0.0890 0.1760 0.0000 offsets; pixscale=0.000063
2014/12/10 02:38:23.294629 N B TRACKERS          TCS OffsetGuiding by (0.000000515021823deg,-0.000012370958059deg) start

2014/12/10 02:38:41.195508 N B TRACKERS          CALLING NEXT with files: /newdata/lbcbtec.20141210.023830_1.fits and /newdata/lbcbtec.20141210.023830_2.fits
2014/12/10 02:38:41.195697 N B TRACKERS IA       IATrackingNext, side:b szFileName1=/newdata/lbcbtec.20141210.023830_1.fits, catfile=/images/IA/track/b_image1.cat, reducedcatfile=/images/IA/track/b_reduced_1.cat, region:910,1235 1,256
2014/12/10 02:38:42.472100 S B TRACKERS          IATrackingNext returned 1 stars, 800.93 background, -0.1020 1.3960 0.0000 offsets; pixscale=0.000063
2014/12/10 02:38:42.472351 N B TRACKERS          TCS OffsetGuiding by (0.000037477885761deg,-0.000079481680322deg) start


2014/12/10 02:40:56.294860 N B TRACKERS IA       IATrackingNext, side:b szFileName1=/newdata/lbcbtec.20141210.024045_1.fits, catfile=/images/IA/track/b_image1.cat, reducedcatfile=/images/IA/track/b_reduced_1.cat, region:910,1235 1,256
2014/12/10 02:40:56.295345 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.024045_1.fits --> /images/IA/track/b_chip1.gif
2014/12/10 02:40:56.295391 N B TRACKERS IA       convert command: /lbt/lbc_runtime/bin/convert /newdata/lbcbtec.20141210.024045_1.fits -crop "2048x256+52+0" -normalize -equalize -resize "450x56" -equalize -rotate 90 /images/IA/track/b_chip1.gif
2014/12/10 02:40:56.935404 N B TRACKERS IA       Converting /newdata/lbcbtec.20141210.024045_2.fits --> /images/IA/track/b_chip2.gif
2014/12/10 02:40:57.305590 N B TRACKERS IA       TrackNext sextractor command = /lbt/lbc_runtime/bin/sex /newdata/lbcbtec.20141210.024045_1.fits -c /images/IA/track/b_assoc1.sex
2014/12/10 02:40:57.372071 N B TRACKERS IA       TrackNext reading catalog file /images/IA/track/b_image1.cat
2014/12/10 02:40:57.372141 W B TRACKERS IA       No Good Sources Found for chip 1, verify image data or change Sextractor configuration files.
2014/12/10 02:40:57.379625 N B TRACKERS IA       /images/IA/track/b_guide.fits ---> /images/IA/track/b_guide.gif
2014/12/10 02:40:57.392383 E B TRACKERS IA       assignOutImageNext: can not process image
2014/12/10 02:40:57.392400 W B TRACKERS          IATrackingNext failed: 1, continue





What went wrong with the IDL code? Seems like it thought it was a reference always? Because it never found good pupils - it complains it won't write a reference file.
There's no .TecLog for blue tonight. why?
Why ini the shared lbciaRun.log do I only see two of these messages, and they're both RED? findpupils side is RED   because it never found any BLUE pupils, this is a message from CalcAbCoeffs which is called after find pupils


[2014-12-10T02:38:56] INFO> ----------------------- DoTecIA: starting -----------------------
[2014-12-10T02:38:56] INFO> Using DataDir=/newdata
[2014-12-10T02:38:56] INFO> Reading cfg /lbccontrol/current/conf/lbcfpia_tec.cfg
[2014-12-10T02:38:56] INFO> Channel: b
[2014-12-10T02:38:56] MESG> searching for files named /newdata/lbcbtec.20141210.*_2.fits
[2014-12-10T02:38:56] MESG> Get_Last_Tech_Files found           15 files, checking times
[2014-12-10T02:38:56] DEBG> Num Files Found: 2
[2014-12-10T02:38:56] DEBG> lbcbtec.20141210.023830_2.fits
[2014-12-10T02:38:56] DEBG> lbcbtec.20141210.023810_2.fits
[2014-12-10T02:38:56] INFO> Now calling LBCFPIA
[2014-12-10T02:38:56] EXTD> Rotator Angle = 28.751800
[2014-12-10T02:38:56] MESG> Searching for pupils... 
[2014-12-10T02:38:57] EXTD> background: 1734.7636
[2014-12-10T02:38:57] EXTD> Blob rejected, inv_moms=0.164518, 0.00128346
[2014-12-10T02:38:57] MESG> ----------------------------------------------------
[2014-12-10T02:38:57] MESG>      No GOOD pupils found... check input image!
[2014-12-10T02:38:57] MESG> ----------------------------------------------------
[2014-12-10T02:38:57] WARN> Pupil analysis failed, will NOT write reference file
[2014-12-10T02:38:57] INFO> ----------------------- DoTecIA: ending -------------------------
[2014-12-10T02:38:57] INFO> -----------------------------------------------------------------
[2014-12-10T02:41:10] SFTW> NEW debug level is 4
[2014-12-10T02:41:10] INFO> -----------------------------------------------------------------
[2014-12-10T02:41:10] INFO> ----------------------- DoTecIA: starting -----------------------
[2014-12-10T02:41:10] INFO> Using DataDir=/newdata
[2014-12-10T02:41:10] INFO> Reading cfg /lbccontrol/current/conf/lbcfpia_tec.cfg
[2014-12-10T02:41:10] INFO> Channel: b
[2014-12-10T02:41:10] ERRL> doRef is 0 but reference file not found, setting doRef to 1
[2014-12-10T02:41:10] MESG> searching for files named /newdata/lbcbtec.20141210.*_2.fits
[2014-12-10T02:41:10] MESG> Get_Last_Tech_Files found           19 files, checking times
[2014-12-10T02:41:10] DEBG> Num Files Found: 3
[2014-12-10T02:41:10] DEBG> lbcbtec.20141210.024026_2.fits
[2014-12-10T02:41:10] DEBG> lbcbtec.20141210.024045_2.fits
[2014-12-10T02:41:10] DEBG> lbcbtec.20141210.024058_2.fits
[2014-12-10T02:41:10] INFO> Now calling LBCFPIA
[2014-12-10T02:41:10] EXTD> Rotator Angle = 30.281090
[2014-12-10T02:41:10] MESG> Searching for pupils... 
[2014-12-10T02:41:11] EXTD> background: 2507.8265
[2014-12-10T02:41:11] MESG> ----------------------------------------------------
[2014-12-10T02:41:11] MESG>      No GOOD pupils found... check input image!
[2014-12-10T02:41:11] MESG> ----------------------------------------------------
[2014-12-10T02:41:11] WARN> Pupil analysis failed, will NOT write reference file
[2014-12-10T02:41:11] INFO> ----------------------- DoTecIA: ending -------------------------
[2014-12-10T02:41:11] INFO> -----------------------------------------------------------------
[2014-12-10T02:43:21] SFTW> NEW debug level is 4
[2014-12-10T02:43:21] INFO> -----------------------------------------------------------------
[2014-12-10T02:43:21] INFO> ----------------------- DoTecIA: starting -----------------------
[2014-12-10T02:43:21] INFO> Using DataDir=/newdata
[2014-12-10T02:43:21] INFO> Reading cfg /lbccontrol/current/conf/lbcfpia_tec.cfg
[2014-12-10T02:43:21] INFO> Channel: b
[2014-12-10T02:43:21] ERRL> doRef is 0 but reference file not found, setting doRef to 1
[2014-12-10T02:43:21] MESG> searching for files named /newdata/lbcbtec.20141210.*_2.fits
[2014-12-10T02:43:21] MESG> Get_Last_Tech_Files found           22 files, checking times
[2014-12-10T02:43:21] DEBG> Num Files Found: 2
[2014-12-10T02:43:21] DEBG> lbcbtec.20141210.024235_2.fits
[2014-12-10T02:43:21] DEBG> lbcbtec.20141210.024255_2.fits
[2014-12-10T02:43:21] INFO> Now calling LBCFPIA
[2014-12-10T02:43:21] EXTD> Rotator Angle = 31.691740
[2014-12-10T02:43:21] MESG> Searching for pupils... 
[2014-12-10T02:43:22] EXTD> background: 1556.6928
[2014-12-10T02:43:22] EXTD> Blob rejected, inv_moms=0.235349, 0.0262397
[2014-12-10T02:43:22] EXTD> Blob rejected, inv_moms=0.221157, 0.00393446
[2014-12-10T02:43:22] MESG> ----------------------------------------------------
[2014-12-10T02:43:22] MESG>      No GOOD pupils found... check input image!
[2014-12-10T02:43:22] MESG> ----------------------------------------------------
[2014-12-10T02:43:22] WARN> Pupil analysis failed, will NOT write reference file
[2014-12-10T02:43:22] INFO> ----------------------- DoTecIA: ending -------------------------
[2014-12-10T02:43:22] INFO> -----------------------------------------------------------------

Topic revision: r10 - 17 Dec 2014, KelleeSummers
This site is powered by FoswikiCopyright © by the contributing authors. All material on this collaboration platform is the property of the contributing authors.
Ideas, requests, problems regarding Foswiki? Send feedback