APACHE POINT OBSERVATORY SDSS 2.5M OBSERVING LOG Thursday October 11, 2001 (MJD 52194) ---=== OBSERVING TEAM ===--- Scot Kleinman (day) Dan Long (night) Atsuko Nitta Kleinman (night) Bruce Gillespie and Discovery Channel film crew (until ~8pm) Russell Owen (UW) Larry Carry (UW) ---=== OBSERVING PLAN ===--- Shake down --- See shake down plan for detail. ---=== OBSERVING SUMMARY ===--- Telescope is not functional. The AZ seems to abort due to the failed fiducial readings. Please the problem section for detail. Many non-telescope and sky required (Watcher, IOP and plug database, sdssProcedures) shakes were done. ---=== OBSERVING LOG ===--- AZ Abort Problems ------------------- We saw AZ abort increase in frequency throughout the night. Dan has written up what he discovered on this problem. It seems that AZ fiducial readings might the cause of the problem. Please see the PROBLEM section. Standard items --------------- We took bias with imager and spectro under iop/sop v3_58_0, astroDa v14_39, astroline v10_9 without any problem. Primary mirror was home and relaxed and the secondary was homed. After the first slew to a star, Dan found a star in the middle of the chip. The collimation was also good. Team French has done absolutely fabulous job taking the scope apart and then putting it back together. There is now a temperature coefficient in the focus equation. From Jim's empirical data the -200 micron/C coefficient was added to default.dat in the TCC. To keep the engineering camera at roughly zero focus, an offset of 1100 was also added. If it turns out that spectro and imager focus values are significantly far from zero now, the offset in the instrument blocks will be adjusted accordingly. The M2 piston versus elevation was found to be in good shape. The focus change was in the noise between elevations of 30 and 75. sdssProcedures --------------- We went through the change log to check what changed and the procedures reflects the changes. Software installation --------------------- Since the FNAL's system was down, we had to wait until 6pm or so before start installing the new software. Watcher ------- Immidiately after the new *ops and watcher were installed, we tried to run the new servers and found a watcher bug that prevented us from starting new servers. Scot contacted Sir Robert and it was fixed on the spot. Please see PR 2781. *We no longer get any error message by doing ''startInterlocks'' on watcher started on commish. Thanks Robert. *The ''changeLog'' on MCP doc showed change up to v2_11. No info on v2_12 is there. *PR 2660: error when starting the system status panel --> fixed. Tested on watcher running from both HOST and COMMISH. PR closed. *PR 2764: Add way to post-mortem debug PLC data --> Got error messages. Sent the PR back to Robert. We followed Robert's suggested test by, startInterlocks -file bob and got a message ''Error: I cannot read bob''. We then did the 2nd suggested test by select the TPM dump from the interlocks Updates menu. We got a Tcl error message saying ''Error:no value given for parameter ''text'' to ''dialog''''. The stack trace gave, no value given for parameter ''text'' to ''dialog'' while executing ''dialog $msg'' invoked from within ''if [catch { startInterlocks -file $tpm_dump_file 040175 msg] { dialog $msg }'' (procedure ''read_tpm_dump'' line 14) invoked from within ''read_tpm_dump .mcp_main.bottom.updates'' invoked from within ''.mcp_main.bottom.updates.menu invoke active'' (''uplevel'' body line 1) invoked from within ''uplevel #0 [list $w invoke active]'' invoked from within ''if {[$w type active] == ''cascade''} { $w postcascade active set menu [$w entrycget active -menu] tkMenuFirstEntry $menu } elseif {[$w type activ ...'' (procedure ''tkMenuInvoke'' line 14) invoked from within ''tkMenuInvoke .mcp_main.bottom.updates.menu 1'' (command bound to event) logTool --------- Eric, thank you for the detail instruction on how to test the PRs. I am sure it was very tedious to write them up, but they were very useful. The default values for ''team'', ''software'' and ''plan'' sections were blank when I did the first loaded. When I started a second logTool, the default values (name, software versions, plan) were there. I don't know how to reproduce the problem -- sorry Eric. *PR 2656: logTool gets the TPM version wrong --> works. PR closed. *PR 2755: goDrift should force /NoRefCoefficients on tcc SET WEATHER/WAVELENGTH calls --> works. PR closed. We did ''tail -f /home/apotop/logs/active/from.tcc25m.log | grep -i WEATHER'' as Eric suggested and looked for the weather messages. While the imager were NOT running, we saw no ''NoRefCoefficients''. While we were taking bias, we saw ''NoRefCoefficients''. Example of error messages with NO imager running: 1002874440.584 0 2 : Cmd='set weather sectrusstemp=7.30, airtemp=7.3, wspeed=9.3, wdir=268, pressure=72352, humid=0.36' with imager running: 1002875892.371 0 2 : Cmd='set weather sectrusstemp=7.70, airtemp=7.7, wspeed=6.8, wdir=278, pressure=72352, humid=0.36 /NoRefCoefficients' *PR 2676: logTool forgets changes if same section selected again --> Works. PR closed. *PR 2701:skippy from logTool issued a garbled command to astrom = logTool from SOP should die --> yes, it does die, but it gives a logTool window. I asked for a ''chage request'' so that the process doesn't start the logTool window. Running logTool from SOP now dies with messages saying, sp1> logTool Error: murmurGUI (whether started as part of logTool or independently) must be started from an IOP, not SOP. MOP is right out. The command still will bring up a blank IOP Log Tool window (the title bar says it is ''IOP Log Tool''. *PR 2654: LogTool truncates murmur messages in the tLatch section --> checked the messages in logViewer's tLatch section with the ''startMurmur -grep tLatch'' (obviously now ALL of the messages, but just one screen full of messages) and the messages were identical and I saw no truncated messages. We noticed no truncated messages in other sections either. PR closed. *PR 2665: logTool mirrors button does the wrong thing --> failed probably due to broken ''m4l'' command. The PR is assigned back to Eric. *PR 2599: Time tracking windows for writing the beginning and the end of the events should be described. --> Work as advertised. PR closed. *PR 2666: logTools jumps! --> I did not experience any cursor jumping. PR closed. *PR 2687: logTool's software section should have an entry for sdssProcedures --> Yes Yes, we have an entry for it. Observers need to type in the version number since sdssProcedure is not a UPS product. * PR 2658: short command to save BD17 calib data from the pool --> Tested getField and getPhotoField as Eric suggested. The instruction had ' getField' and 'getFrame' mixed up sometimes which caused some confusions. We did not test all options for the commands, but only the ones Eric gave examples of. Since both of the commands work as described in the help string for the few examples we tried, we closed the PR. Here are the commands we tried: im> getField [getRunNumber] 20 /home/[exec whoami]/getFrameTestResults/ -->worked as advertised. im> getField [getRunNumber] 22 /home/[exec whoami]/objectOfInterest -filter g -dewar 2 --> worked as advertised. im> getPhotoFields --> works as advertised. In the shake down request, im> getPhotoFields 25 12 copies over all images of the region of sky covered by the r ccd in dewar 4 in frames 13 through 25, and places them in /data/photo/optics/[mjd4Gang]. But according to the "help getPhotoFields", the second number is suppose to be the number of fields, not the minimum_frame_number one wants to copy over. We discovered this after trying the command couple times and having unintended frames copied over. im> getPhotoFields 25 12 --> doesn't work as advertised, but works as the "help" string says. *mcpSystemStatus -instID --> This gives the inst ID as advertised. plate database --------------- PR 2263 there is now a key to what the colors mean on day/night planning pages --> yes there is. (PR was already closed) PR2632 - start, stop times in Mountain Time are now listed for plates --> Yes they are. (PR was already closed) PR2672 - priority of plates is now show under night planning. --> Yes and PR closed. TCC ----- We are still with, 0 0 F Modu=''mir_o_MoveOneGalil''; Text='' Sec returned an error message'' 0 0 I Received=''?GOCORR one or more axes did not end on a full step 0, 0, 0, 0, 1'' 0 0 W Text=''failed to adjust collimation and focus'' Spectro exposure number and file number out of sync --------------------------------------------------- We found the exposure number and file number being out of sync, but fixed it following the procedure in folklore. ---=== IMAGING RUN SUMMARY ===--- 2641 100 O bias 160.65 163.77 35 2642 100 O ignore -143.55 -132.87 85 ---=== IMAGING RUN DETAILS ===--- ---=== SKIPPY RESULTS ===--- ---=== LTMATCH RESULTS ===--- ---=== SPECTROSCOPY DATA SUMMARY ===--- 04:19 11173 0.0 bias 09:22 11174 0.0 bias ---=== TELESCOPE OFFSETS AND SCALE I ===--- ---=== TELESCOPE OFFSETS AND SCALE II ===--- ---=== DATA TAPE SUMMARY ===--- ---=== FOCUS LOG ===--- ---=== WEATHER LOG ===--- 20:55 43 16 11 259 (W) 535 - 21:28 44 17 16 263 (W) 496 - 22:04 44 18 17 265 (W) 483 - 22:36 45 19 21 276 (W) 449 - 23:08 45 18 22 262 (W) 436 - 23:41 45 19 23 268 (W) 414 - 00:13 45 19 17 267 (W) 482 - 00:45 45 19 15 268 (W) 504 - 01:17 46 17 18 270 (W) 526 - 01:49 45 18 18 268 (W) 544 - 02:26 45 19 19 272 (W) 562 - 02:58 45 20 20 283 (WNW) 512 - 03:30 45 21 21 272 (W) 599 - 05:19 44 22 14 268 (W) 541 - ---=== TELESCOPE STATUS ===--- Telescope at stow position with auto fill system connected. ---=== SOFTWARE USED ===--- iop/sop v3_58_0 watcher v2_12_0 TPM v2_4_2 (although the TPM shows ''NOCVS'' due to the bad connection we had today with FNAL) MCP v5_6_1, v5_5_0 astroDa v14_39 astroling v10_9 TCC 2.5.5 tccMon v1_7 sdssProcedures v1_31 ---=== MIRROR NUMBERS ===--- MIrror data from TCC 0 0 I PrimDesOrient=0.00, -11.80, 23.00, 1256.90, 642.10; PrimConstRMS=0.00 0 0 I SecDesOrient=817.00, -3.40, -10.00, 0.00, 133.82; SecConstRMS=0.00 0 0 I PrimCmdMount=5377.,-3699.,923.,-208.,31535.,30647.; CmdDTime=2.1 0 0 I PrimMount=5400., -3700., 900., -200., 31550., 30650. 0 0 I PrimOrient=0.00, -12.16, 22.81, 1257.41, 642.19 0 0 I SecCmdMount=1045291.,1010925.,1032021.,-10450.,-9826.; CmdDTime=20.8 MIGs from tpm .073 -.091 .063 -1.466 1.803 1.374 1.124 .846 .876 .242 ---=== PROBLEMS IN DETAIL ===--- PR 2782/2.5m_telescope: Repeated azimuth aborts and loss of fiducial crossings. This is an example of a problem we have had numerous times tonight. In each case the azimuth goes out of closed loop at the start of a slew, even if the telescope is currently tracking. An axis init from the TCC does not clear the problem. This happens with both v5_6_1 and v5_5_0. As was later discovered this may just be an odd reaction to seeing no fiducial crossings. The PR will be filed under 2.5m telescope, but I will leave the log details just in case there is a coincidental software problem. Oddly enough all one has to do to move the axis again is clear the brake from the mcp. The axis is reenabled and can be moved from the Menu. This makes some sense from the interlocks standpoint as when they are checked, nothing is wrong. It is then possible to move via the TCC ( after returning the semaphore ). Another way to restore motion is to issue two axis inits from the TCC in rapid succession. Below are excerpts from the telrun session, the murmur log, and the mcp tap for one of the events. Note that after reverting to v5_5_0 we had the typical issue of setting the fiducials. At this time we noticed that the azimuth was registering no fiducial crossings. Checking the murmur log shows that the last fiducial crossing seen in azimuth was 00:14 MDT, and that there were large gaps in readings starting around 23:00. At around 03:00 a 360 degree sweep in azimuth was done. The -15, 15, and 255 degree fiducials registered each time they were crossed ( both directions ). So it would seem the fiducial failure is not complete and may be a head cleaning or adjustment issue. After sending a track command with the TCC the azimuth fails to move. First the mcp tap info. 1002866104.31 AZ STATUS 1002866104.36 -1.204386 -5.879257 21336.303675 0 0.000000 OK 1002866104.39 ALT STATUS 1002866104.44 73.762895 -0.028720 21336.384108 0 0.000000 OK 1002866104.55 ROT STATUS 1002866104.59 -0.607926 -5.158480 21336.542307 0 0.000000 OK 1002866105.22 AZ DRIFT 1002866105.31 -1.219516 -0.014355 21337.272723 OK 1002866105.42 ALT DRIFT 1002866105.51 73.762783 -0.000071 21337.478978 OK 1002866105.58 ROT DRIFT 1002866105.67 -0.622599 -0.012594 21337.635239 OK 1002866105.75 AZ MS.ON 1002866105.75 OK 1002866105.82 ALT MS.ON 1002866105.83 OK 1002866105.90 ROT MS.ON 1002866105.91 OK 1002866106.07 AZ MOVE -1.2631161 -0.0143550 21340.31000 1002866106.08 OK 1002866106.21 ALT MOVE 73.7625820 -0.0000710 21340.31000 1002866106.22 OK 1002866106.37 ROT MOVE -0.6562849 -0.0125940 21340.31000 1002866106.37 OK 1002866106.53 AZ MOVE -1.2441378 0.0856450 21341.31000 1002866106.54 OK 1002866106.69 ALT MOVE 73.7458443 -0.0500710 21341.31000 1002866106.69 OK 1002866106.85 ROT MOVE -0.6069233 0.1436560 21341.56000 1002866106.85 OK 1002866107.01 AZ MOVE 7.7625246 1.9000000 21350.38177 1002866107.02 OK 1002866107.17 ALT MOVE 67.5765472 -1.1119202 21351.92849 1002866107.17 OK 1002866107.33 ROT MOVE 6.1506308 1.8437500 21348.36038 1002866107.33 OK 1002866107.49 AZ MOVE 9.7291912 2.0000000 21351.38177 1002866107.50 OK 1002866107.65 ALT MOVE 66.4312936 -1.1619202 21352.92849 1002866107.66 OK 1002866107.81 ROT MOVE 8.5855266 2.0000000 21349.61038 1002866107.81 OK 1002866107.97 AZ MOVE 58.7635421 2.0000000 21375.89895 1002866107.98 OK 1002866108.13 ALT MOVE 65.2860401 -1.1119202 21353.92849 1002866108.14 OK 1002866108.29 ROT MOVE 47.4786966 2.0000000 21369.05696 1002866108.29 OK 1002866108.45 AZ MOVE 60.7302088 1.9000000 21376.89895 1002866108.46 OK 1002866108.61 ALT MOVE 59.1151134 -0.0467033 21364.58066 1002866108.62 OK 1002866108.77 ROT MOVE 49.9135925 1.8437500 21370.30696 1002866108.77 OK 1002866108.93 AZ MOVE 69.7323094 0.0957066 21385.92042 1002866108.94 OK 1002866109.09 ALT MOVE 59.1017434 0.0032967 21365.58066 1002866109.09 OK 1002866109.25 ROT MOVE 56.6650590 0.1538857 21377.06642 1002866109.25 OK 1002866109.42 AZ MOVE 69.7613493 -0.0042934 21386.92042 1002866109.42 OK 1002866109.57 ALT MOVE 59.1720933 0.0032967 21386.92042 1002866109.57 OK 1002866109.73 ROT MOVE 56.7272077 -0.0023643 21378.31642 1002866109.74 OK 1002866109.81 OK 1002866109.89 OK 1002866110.05 ROT MOVE 56.7068649 -0.0023643 21386.92042 1002866110.06 OK 1002866110.17 AZ MS.OFF 21385.92042 1002866110.18 OK 1002866110.25 ALT MS.OFF 21385.92042 1002866110.25 OK 1002866110.33 ROT MS.OFF 21385.92042 1002866110.33 OK Next the extract from the murmur log. Oct 11 23:55:05 sdssmcp TCC dsc_I_trcInfo azimuth MS.OFF scheduled: 0 Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo azimuth MS.OFF Oct 11 23:55:05 sdssmcp TCC dsc_I_trcInfo DRIFT azimuth: -1.219516 -0 .014355 21337.272723 Oct 11 23:55:05 sdssmcp tmAz dsc_I_trcInfo azimuth drift_break: frames left=10 Oct 11 23:55:05 sdssmcp tmAz dsc_S_trcSucc drifting azimuth: v = -3687 cts/sec Oct 11 23:55:05 sdssmcp TCC dsc_I_trcInfo altitude MS.OFF scheduled: 0 Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo altitude MS.OFF Oct 11 23:55:05 sdssmcp TCC dsc_I_trcInfo DRIFT altitude: 73.762783 - 0.000071 21337.478978 Oct 11 23:55:05 sdssmcp TCC dsc_I_trcInfo rotator MS.OFF scheduled: 0 Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo rotator MS.OFF Oct 11 23:55:05 sdssmcp tmAlt dsc_I_trcInfo altitude drift_break: frames left=10 Oct 11 23:55:05 sdssmcp tmAlt dsc_S_trcSucc drifting altitude: v = -18 cts/sec Oct 11 23:55:05 sdssmcp TCC dsc_I_trcInfo DRIFT rotator: -0.622599 -0 .012594 21337.635239 Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo azimuth MS.ON Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo altitude MS.ON Oct 11 23:55:05 sdssmcp tmRot dsc_I_trcInfo rotator drift_break: frames left=10 Oct 11 23:55:05 sdssmcp tmRot dsc_S_trcSucc drifting rotator: v = -2126 cts/sec Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo rotator MS.ON Oct 11 23:55:05 sdssmcp tLatch dsc_I_trcInfo Applying correction 207 to rotator Oct 11 23:55:10 sdssmcp TCC dsc_I_trcInfo azimuth MS.OFF scheduled: 44 Oct 11 23:55:10 sdssmcp TCC dsc_I_trcInfo altitude MS.OFF scheduled: 44 Oct 11 23:55:10 sdssmcp TCC dsc_I_trcInfo rotator MS.OFF scheduled: 44 Oct 11 23:55:11 sdssmcp ampMgt dsc_E_trcErr MGT: bad az state ABORT_EVENT: ID_ERROR_LIMIT Oct 11 23:55:11 sdssmcp ampMgt dsc_I_trcInfo Setting brake for axis azimuth Oct 11 23:55:11 sdssmcp tBrakes dsc_I_trcInfo Taking axis azimuth out of closed loop Oct 11 23:55:12 sdssmcp tmAz dsc_S_trcSucc Last frame in buffer: p=9. 785651 Oct 11 23:55:12 sdssmcp tLatch dsc_E_trcErr Failed to identify rotator fiducial at 1.00 Oct 11 23:55:13 sdsshost IOP 319357 TEXTONLY CAMERA: sp2 camera: sent '' CAMCHECK ''. Read<8: 2>: ''CAMCHECK ok '' Oct 11 23:55:14 sdssmcp tLatch dsc_I_trcInfo Intermediate rot fiducial 3 .54 deg Oct 11 23:55:15 sdssmcp tmAz dsc_S_trcSucc Last frame in buffer: p=60. 751192 Oct 11 23:55:15 sdsshost IOP 319357 TEXTONLY CAMERA: sp2 camera: sent '' LN2STAT ''. Read<1: 3>: ''LN2STAT LN2_FILL ON FILLTIME 90 # MINUTES NEXT_FILL ... Oct 11 23:55:15 sdsshost IOP 319357 TEXTONLY ... 23 # MINUTES nNORM_RETRIG 1 FILL_MODE COLD WARM_FILLTIME 15_MINUTES nWARM_FILLS 2 WARM_RETRIG 6 ... Oct 11 23:55:15 sdsshost IOP 319357 TEXTONLY ... FILLFAULT NO nEMPTY_TRIGGER NO # AUTOFILL SETUP EMPTY CAMERA_3 1 0 CAMERA_2 1 ... Oct 11 23:55:15 sdsshost IOP 319357 TEXTONLY ... 0 ok '' Oct 11 23:55:16 sdssmcp tLatch dsc_I_trcInfo rot fiducial 77 (5.74) deg Oct 11 23:55:16 sdssmcp tLatch dsc_I_trcInfo err = 2 poserr = 23 ticks Oct 11 23:55:16 sdssmcp tLatch dsc_I_trcInfo Applying correction -23 to rotator Oct 11 23:55:16 sdssmcp tmAz dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:55:16 sdssmcp tmAz dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:55:16 sdssmcp tmAz dsc_I_trcInfo azimuth frame_break: frames left=4 Oct 11 23:55:17 sdssmcp tLatch dsc_I_trcInfo Intermediate rot fiducial 8 .28 deg Oct 11 23:55:18 sdssmcp tLatch dsc_I_trcInfo rot fiducial 76 (10.48) deg Oct 11 23:55:18 sdssmcp tLatch dsc_I_trcInfo err = -143 poserr = 13 ticks Oct 11 23:55:18 sdssmcp tLatch dsc_I_trcInfo Applying correction -13 to rotator after one TCC axis init: The TCC telrun output looks normal axis init 0 0 I Modu=''axe_o_Init''; Text=''locking ports: Az , Alt, Rot'' 0 0 I Modu=''axe_o_Init''; Text=''initializing: Az , Alt, Rot'' 0 0 I Modu=''exe_BrdTelPos''; Text=''1621500 packets sent successfully'' 0 0 I Modu=''axe_o_Init''; Text=''setting time of: Az , Alt, Rot'' 0 0 I Modu=''axe_o_Init''; Text=''sending init files (if they exist) to: Az , Alt, Rot'' 0 0 I AzStat=-0.426808, 0.000000, 4509583096.62983, 0x00000000; AzDTime=0.0 0 0 I AltStat=59.533379, 0.000000, 4509583096.90337, 0x00000000; AltDTime=-0.1 0 0 I RotStat=56.442764, 0.000000, 4509583097.22392, 0x00000000; RotDTime=-0.1; RotID=1, 0 0 0 I AxisInit=''TTT'' 0 8 : Cmd='axis init' 0 0 I TCCStatus=''HHH'',''NNN''; TCCPos=NaN,NaN,NaN; AxePos=-0.43,59.53,56.44 The mcp tap output looks normal 1002866259.58 AZ INIT 1002866261.07 OK 1002866261.14 ALT INIT 1002866262.40 OK 1002866262.50 ROT INIT 1002866263.71 OK 1002866263.84 AZ SET.TIME 10 12 2001 05 58 15.71 1002866264.20 OK 1002866264.32 ALT SET.TIME 10 12 2001 05 58 16.19 1002866264.32 OK 1002866264.48 ROT SET.TIME 10 12 2001 05 58 16.35 1002866264.48 OK 1002866264.63 AZ STATUS 1002866264.68 -0.426808 0.000000 21496.629828 0 0.000000 OK 1002866264.75 AZ STATUS 1002866264.80 -0.426808 0.000000 21496.743928 0 0.000000 OK 1002866264.91 ALT STATUS 1002866264.95 59.533379 0.000000 21496.903367 0 0.000000 OK 1002866265.07 ALT STATUS 1002866265.11 59.533333 0.000000 21497.063630 0 0.000000 OK 1002866265.23 ROT STATUS 1002866265.28 56.442764 0.000000 21497.223918 0 0.000000 OK 1002866265.39 ROT STATUS 1002866265.44 56.442770 0.000000 21497.388801 0 0.000000 OK The murmur log shows that at some point the azimuth goes out of closed loop apparently due to dsc_E_trcErr MGT: bad az state ABORT_EVENT: ID_ERROR_LIMIT Oct 11 23:57:40 sdssmcp tBrakes dsc_I_trcInfo Putting axis azimuth into closed loop Oct 11 23:57:40 sdssmcp tBrakes dsc_I_trcInfo Stopping axis azimuth Oct 11 23:57:41 sdssmcp TCC dsc_I_trcInfo altitude MS.OFF scheduled: 0 Oct 11 23:57:41 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis altitude: 2 Oct 11 23:57:41 sdssmcp tLatch dsc_I_trcInfo altitude MS.OFF Oct 11 23:57:41 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis altitude: 3 Oct 11 23:57:42 sdssmcp TCC dsc_I_trcInfo rotator MS.OFF scheduled: 0 Oct 11 23:57:42 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis rotator: 4 Oct 11 23:57:42 sdssmcp tLatch dsc_I_trcInfo rotator MS.OFF Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:43 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:44 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:44 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:44 sdssmcp tmAlt dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:44 sdssmcp tmAlt dsc_I_trcInfo altitude frame_break: frames left=4 Oct 11 23:57:44 sdssmcp tmRot dsc_I_trcInfo CALC FRAME: next frame required to finish Oct 11 23:57:44 sdssmcp tmRot dsc_I_trcInfo (throttle) CALC FRAME: next frame required to finish Oct 11 23:57:44 sdssmcp tmRot dsc_I_trcInfo rotator frame_break: frames left=4 Oct 11 23:57:44 sdsshost IOP 319343 TEXTONLY Checking gang transfer for node ad2 Oct 11 23:57:44 sdssmcp tmAz dsc_I_trcInfo azimuth no next frame: frames left=1 Oct 11 23:57:45 sdssmcp ampMgt dsc_E_trcErr MGT: bad az state ABORT_EVENT: ID_ERROR_LIMIT Oct 11 23:57:45 sdssmcp ampMgt dsc_I_trcInfo Setting brake for axis azimuth Oct 11 23:57:45 sdssmcp tBrakes dsc_I_trcInfo Taking axis azimuth out of closed loop Now sending two axis inits in rapid succession. Again the TCC telrun output looks normal. axis init 0 0 I Modu=''axe_o_Init''; Text=''locking ports: Az , Alt, Rot'' 0 0 I Modu=''axe_o_Init''; Text=''initializing: Az , Alt, Rot'' 0 0 I Modu=''axe_o_Init''; Text=''setting time of: Az , Alt, Rot'' 0 0 I Modu=''axe_o_Init''; Text=''sending init files (if they exist) to: Az , Alt, Rot'' 0 0 I AzStat=-0.427018, 0.000000, 4509583450.71178, 0x00000000; AzDTime=0.0 0 0 I AltStat=59.533344, 0.000000, 4509583450.98643, 0x00000000; AltDTime=-0.1 0 0 I RotStat=56.442829, 0.000000, 4509583451.30654, 0x00000000; RotDTime=-0.1; RotID=1, 0 0 0 I AxisInit=''TTT'' axis init 0 8 : Cmd='axis init' 0 0 I Modu=''axe_o_Init''; Text=''locking ports: Az , Alt, Rot'' 0 0 I TCCStatus=''HHH'',''NNN''; TCCPos=NaN,NaN,NaN; AxePos=-0.43,59.53,56.44 0 0 I Modu=''axe_o_Init''; Text=''initializing: Az , Alt, Rot'' 0 0 I SpiderInstAng = 56.039008, -0.002448, 4509583101.92042 0 0 I Modu=''axe_o_Init''; Text=''setting time of: Az , Alt, Rot'' 0 0 I Modu=''axe_o_Init''; Text=''sending init files (if they exist) to: Az , Alt, Rot'' 0 0 I AzStat=-0.427014, 0.000000, 4509583455.83172, 0x00000000; AzDTime=0.0 0 0 I AltStat=59.533344, 0.000000, 4509583456.10646, 0x00000000; AltDTime=-0.1 0 0 I RotStat=56.442829, 0.000000, 4509583456.42635, 0x00000000; RotDTime=-0.1; RotID=1, 0 0 0 I AxisInit=''TTT'' So does the mcp tap. 1002866614.18 AZ INIT 1002866615.71 OK 1002866615.78 ALT INIT 1002866616.93 OK 1002866616.99 ROT INIT 1002866618.10 OK 1002866618.24 AZ SET.TIME 10 12 2001 06 04 10.12 1002866618.25 OK 1002866618.40 ALT SET.TIME 10 12 2001 06 04 10.28 1002866618.41 OK 1002866618.56 ROT SET.TIME 10 12 2001 06 04 10.44 1002866618.57 OK 1002866618.71 AZ STATUS 1002866618.76 -0.427018 0.000000 21850.711779 0 0.000000 OK 1002866618.83 AZ STATUS 1002866618.88 -0.427006 0.000000 21850.826742 0 0.000000 OK 1002866618.99 ALT STATUS 1002866619.04 59.533344 0.000000 21850.986431 0 0.000000 OK 1002866619.15 ALT STATUS 1002866619.20 59.533336 0.000000 21851.147089 0 0.000000 OK 1002866619.31 ROT STATUS 1002866619.36 56.442829 0.000000 21851.306537 0 0.000000 OK 1002866619.47 ROT STATUS 1002866619.52 56.442823 0.000000 21851.469853 0 0.000000 OK 1002866619.72 AZ INIT 1002866620.86 OK 1002866620.90 ALT INIT 1002866622.06 OK 1002866622.10 ROT INIT 1002866623.22 OK 1002866623.36 AZ SET.TIME 10 12 2001 06 04 15.24 1002866623.37 OK 1002866623.52 ALT SET.TIME 10 12 2001 06 04 15.39 1002866623.53 OK 1002866623.68 ROT SET.TIME 10 12 2001 06 04 15.55 1002866623.69 OK 1002866623.83 AZ STATUS 1002866623.88 -0.427014 0.000000 21855.831719 0 0.000000 OK 1002866623.95 AZ STATUS 1002866624.00 -0.427014 0.000000 21855.947198 0 0.000000 OK 1002866624.11 ALT STATUS 1002866624.16 59.533344 0.000000 21856.106463 0 0.000000 OK 1002866624.27 ALT STATUS 1002866624.32 59.533364 0.000000 21856.267552 0 0.000000 OK 1002866624.43 ROT STATUS 1002866624.48 56.442829 0.000000 21856.426354 0 0.000000 OK 1002866624.59 ROT STATUS 1002866624.64 56.442829 0.000000 21856.589936 0 0.000000 OK This time the murmur log does not show the azimuth going out of closed loop. Oct 12 00:03:34 sdssmcp TCC dsc_I_trcInfo azimuth MS.OFF scheduled: 0 Oct 12 00:03:34 sdssmcp TCC dsc_S_trcSucc INIT axis azimuth: not running: ABORT_EVENT Oct 12 00:03:34 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis azimuth: 0 Oct 12 00:03:34 sdssmcp tLatch dsc_I_trcInfo azimuth MS.OFF Oct 12 00:03:34 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis azimuth: 1 Oct 12 00:03:34 sdssmcp TCC dsc_I_trcInfo Clearing brake for axis azimuth Oct 12 00:03:34 sdssmcp tBrakes dsc_I_trcInfo Putting axis azimuth into closed loop Oct 12 00:03:34 sdssmcp tBrakes dsc_I_trcInfo Stopping axis azimuth Oct 12 00:03:35 sdssmcp TCC dsc_I_trcInfo altitude MS.OFF scheduled: 0 Oct 12 00:03:35 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis altitude: 2 Oct 12 00:03:35 sdssmcp tLatch dsc_I_trcInfo altitude MS.OFF Oct 12 00:03:35 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis altitude: 3 Oct 12 00:03:36 sdssmcp TCC dsc_I_trcInfo rotator MS.OFF scheduled: 0 Oct 12 00:03:37 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis rotator: 4 Oct 12 00:03:37 sdssmcp tLatch dsc_I_trcInfo rotator MS.OFF Oct 12 00:03:39 sdssmcp TCC dsc_I_trcInfo azimuth MS.OFF scheduled: 0 Oct 12 00:03:39 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis azimuth: 0 Oct 12 00:03:39 sdssmcp tLatch dsc_I_trcInfo azimuth MS.OFF Oct 12 00:03:39 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis azimuth: 1 Oct 12 00:03:40 sdssmcp TCC dsc_I_trcInfo altitude MS.OFF scheduled: 0 Oct 12 00:03:40 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis altitude: 2 Oct 12 00:03:40 sdssmcp tLatch dsc_I_trcInfo altitude MS.OFF Oct 12 00:03:40 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis altitude: 3 Oct 12 00:03:41 sdsshost TMS 396909 TEXTONLY transfering gangs to unix Oct 12 00:03:42 sdssmcp TCC dsc_I_trcInfo rotator MS.OFF scheduled: 0 Oct 12 00:03:42 sdssmcp TCC dsc_I_trcInfo Resetting amp for axis rotator: 4 Oct 12 00:03:42 sdssmcp tLatch dsc_I_trcInfo rotator MS.OFF The telescope then slews successfully. =============================================================================== PR 2665: Nothing shows up in the mirror section after pressing the mirrors button. When it is tried directly from IOP, the following is seen. im> m4l Time dpTemp dewPt Press TmpOut HumOut Windd Winds 02:28 45.3 19.8 21.37 35 269.8 18.6 7 PRIMARY: Error: Syntax Error: expected 1 more required positional parameters ftclParseArg: Error parsing the command USAGE: vputs [level] [variable] -help im> tb Syntax Error: expected 1 more required positional parameters ftclParseArg: Error parsing the command USAGE: vputs [level] [variable] -help while executing ''shTclParseArg $args $opts [info level 0]'' invoked from within ''if {[shTclParseArg $args $opts [info level 0 ]] == 0} { return }'' (procedure ''vputs'' line 3) invoked from within ''vputs ''--------'''' (procedure ''mirrorPositionForLog'' line 84) invoked from within ''mirrorPositionForLog'' invoked from within ''m4log'' (''uplevel'' body line 1) invoked from within ''uplevel 1 [tclx_unknown2 $args]'' invoked from within ''return [uplevel 1 [tclx_unknown2 $args]]...'' (procedure ''unknown'' line 8) invoked from within ''m4l'' MIrror data from TCC 0 0 I PrimDesOrient=0.00, -11.80, 23.00, 1256.90, 642.10; PrimConstRMS=0.00 0 0 I SecDesOrient=817.00, -3.40, -10.00, 0.00, 133.82; SecConstRMS=0.00 0 0 I PrimCmdMount=5377.,-3699.,923.,-208.,31535.,30647.; CmdDTime=2.1 0 0 I PrimMount=5400., -3700., 900., -200., 31550., 30650. 0 0 I PrimOrient=0.00, -12.16, 22.81, 1257.41, 642.19 0 0 I SecCmdMount=1045291.,1010925.,1032021.,-10450.,-9826.; CmdDTime=20.8 MIGs from tpm .073 -.091 .063 -1.466 1.803 1.374 1.124 .846 .876 .242