[ 2011-Sep-14 20:44:53.279812 ] {}: Adding FlexOstream group: test () [ 2011-Sep-14 20:44:53.280003 ] {}: Adding FlexOstream group: driver1 () [ 2011-Sep-14 20:44:53.280327 ] {}: Adding FlexOstream group: goby::acomms::modemdriver::out::1 () [ 2011-Sep-14 20:44:53.280452 ] {}: Adding FlexOstream group: goby::acomms::modemdriver::in::1 () [ 2011-Sep-14 20:44:53.282893 ] {}: [[DriverConfig]] modem_id: 1 [ 2011-Sep-14 20:44:53.283013 ] {}: serial_port: "/dev/ttyUSB1" [ 2011-Sep-14 20:44:53.283128 ] {}: [micromodem.protobuf.Config.nvram_cfg]: "AGC,0" [ 2011-Sep-14 20:44:53.283243 ] {}: [micromodem.protobuf.Config.nvram_cfg]: "AGN,0" [ 2011-Sep-14 20:44:53.283358 ] {}: [micromodem.protobuf.Config.remus_lbl] { [ 2011-Sep-14 20:44:53.283471 ] {}: enable_beacons: 7 [ 2011-Sep-14 20:44:53.283586 ] {}: } [ 2011-Sep-14 20:44:53.283699 ] {}: [ 2011-Sep-14 20:44:53.283893 ] {goby::acomms::modemdriver::out::1}: D: opening serial port /dev/ttyUSB1 @ 19200 [ 2011-Sep-14 20:44:54.003143 ] {goby::acomms::modemdriver::out::1}: D: $CCCLK,2011,9,14,20,44,54*79 [ 2011-Sep-14 20:44:54.003649 ] {goby::acomms::modemdriver::out::1}: ^ Set clock, host to modem [ 2011-Sep-14 20:44:56.009816 ] {goby::acomms::modemdriver::in::1}: D: $CACLK,2011,09,14,20,44,54*4B [ 2011-Sep-14 20:44:56.010327 ] {goby::acomms::modemdriver::in::1}: ^ Time/Date message, modem to host [ 2011-Sep-14 20:44:56.011016 ] {}: D: reported time: 2011-Sep-14 20:44:55 [ 2011-Sep-14 20:44:56.012882 ] {}: D: reported time: 2011-Sep-14 20:44:56 [ 2011-Sep-14 20:44:56.113495 ] {goby::acomms::modemdriver::out::1}: D: $CCCFG,CST,1*37 [ 2011-Sep-14 20:44:56.113652 ] {goby::acomms::modemdriver::out::1}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.214848 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,CST,1*35 [ 2011-Sep-14 20:44:56.215350 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle statistics message 1 [ 2011-Sep-14 20:44:56.317452 ] {goby::acomms::modemdriver::out::1}: D: $CCCFG,AGC,0*37 [ 2011-Sep-14 20:44:56.317954 ] {goby::acomms::modemdriver::out::1}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.419985 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,AGC,0*35 [ 2011-Sep-14 20:44:56.420475 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on automatic gain control [ 2011-Sep-14 20:44:56.522399 ] {goby::acomms::modemdriver::out::1}: D: $CCCFG,AGN,0*3A [ 2011-Sep-14 20:44:56.522886 ] {goby::acomms::modemdriver::out::1}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.625042 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,AGN,0*38 [ 2011-Sep-14 20:44:56.625554 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Analog Gain (50 is 6 dB, 250 is 30 dB) [ 2011-Sep-14 20:44:56.727528 ] {goby::acomms::modemdriver::out::1}: D: $CCCFG,SRC,1*31 [ 2011-Sep-14 20:44:56.728026 ] {goby::acomms::modemdriver::out::1}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.829789 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SRC,1*33 [ 2011-Sep-14 20:44:56.829936 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Default Source Address [ 2011-Sep-14 20:44:56.930696 ] {goby::acomms::modemdriver::out::1}: D: $CCCFG,REV,1*32 [ 2011-Sep-14 20:44:56.930839 ] {goby::acomms::modemdriver::out::1}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:57.031791 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,REV,1*30 [ 2011-Sep-14 20:44:57.032064 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CAREV message [ 2011-Sep-14 20:44:57.133202 ] {goby::acomms::modemdriver::out::1}: D: $CCCFG,RXP,1*29 [ 2011-Sep-14 20:44:57.133409 ] {goby::acomms::modemdriver::out::1}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:57.234357 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,RXP,1*2B [ 2011-Sep-14 20:44:57.234510 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXP message [ 2011-Sep-14 20:44:57.335613 ] {goby::acomms::modemdriver::out::1}: D: $CCCFQ,ALL*39 [ 2011-Sep-14 20:44:57.336126 ] {goby::acomms::modemdriver::out::1}: ^ Query configuration parameter, host to modem [ 2011-Sep-14 20:44:57.438285 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,AGC,0*35 [ 2011-Sep-14 20:44:57.438816 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on automatic gain control [ 2011-Sep-14 20:44:57.439533 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,AGN,0*38 [ 2011-Sep-14 20:44:57.440039 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Analog Gain (50 is 6 dB, 250 is 30 dB) [ 2011-Sep-14 20:44:57.440737 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,AGX,1*2F [ 2011-Sep-14 20:44:57.441513 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.442225 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,ASD,0*26 [ 2011-Sep-14 20:44:57.442708 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Always Send Data. Tells the modem to send test data when the user does not provide any. [ 2011-Sep-14 20:44:57.443406 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BBD,0*34 [ 2011-Sep-14 20:44:57.443900 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: PSK Baseband data dump to serial port [ 2011-Sep-14 20:44:57.444576 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BSP,1*30 [ 2011-Sep-14 20:44:57.445070 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Boot loader serial port [ 2011-Sep-14 20:44:57.445752 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BND,3*3B [ 2011-Sep-14 20:44:57.446275 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Frequency Bank (1, 2, 3 for band A, B, or C, 0 for user-defined PSK only band) [ 2011-Sep-14 20:44:57.446950 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BR1,3*52 [ 2011-Sep-14 20:44:57.447449 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Baud rate for serial port 1 (3 = 19200) [ 2011-Sep-14 20:44:57.448118 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BR2,3*51 [ 2011-Sep-14 20:44:57.448620 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Baud rate for serial port 2 (3 = 19200) [ 2011-Sep-14 20:44:57.449314 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BRN,0*2E [ 2011-Sep-14 20:44:57.449820 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Run bootloader at next revert [ 2011-Sep-14 20:44:57.555210 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,BW0,4000*61 [ 2011-Sep-14 20:44:57.555726 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Bandwidth for Band 0 PSK CPR 0-1 Coprocessor power toggle switch 1 [ 2011-Sep-14 20:44:57.556449 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,CPR,1*30 [ 2011-Sep-14 20:44:57.556944 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.557663 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,CRL,50*18 [ 2011-Sep-14 20:44:57.558186 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle init reverb lockout (ms) 50 [ 2011-Sep-14 20:44:57.558856 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,CST,1*35 [ 2011-Sep-14 20:44:57.559357 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle statistics message 1 [ 2011-Sep-14 20:44:57.560025 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,CTO,10*19 [ 2011-Sep-14 20:44:57.560526 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle init timeout (sec) 10 [ 2011-Sep-14 20:44:57.561489 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DBG,0*31 [ 2011-Sep-14 20:44:57.562007 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Enable low-level debug messages 0 [ 2011-Sep-14 20:44:57.562692 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DGM,0*3E [ 2011-Sep-14 20:44:57.563209 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Diagnostic messaging 0 [ 2011-Sep-14 20:44:57.563878 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DOP,0*2B [ 2011-Sep-14 20:44:57.564377 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CADOP message [ 2011-Sep-14 20:44:57.565068 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DQF,1*22 [ 2011-Sep-14 20:44:57.565573 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CADQF message [ 2011-Sep-14 20:44:57.566269 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DTH,108*21 [ 2011-Sep-14 20:44:57.566743 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Matched filter signal threshold, FSK [ 2011-Sep-14 20:44:57.567457 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DTO,2*2D [ 2011-Sep-14 20:44:57.567955 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Data request timeout (sec) [ 2011-Sep-14 20:44:57.568664 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,DTP,90*09 [ 2011-Sep-14 20:44:57.569186 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Matched filter signal threshold, PSK [ 2011-Sep-14 20:44:57.569866 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,ECD,50*07 [ 2011-Sep-14 20:44:57.570373 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Int Delay at end of cycle (ms) [ 2011-Sep-14 20:44:57.675679 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,EFF,20*07 [ 2011-Sep-14 20:44:57.676216 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Feedforward taps for the LMS equalizer [ 2011-Sep-14 20:44:57.676889 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,EFB,10*00 [ 2011-Sep-14 20:44:57.677380 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Feedback taps for the LMS equalizer [ 2011-Sep-14 20:44:57.678038 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,FC0,25120*41 [ 2011-Sep-14 20:44:57.678525 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Carrier at Band 0 PSK only [ 2011-Sep-14 20:44:57.679202 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,FML,200*35 [ 2011-Sep-14 20:44:57.679665 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: PSK FM probe length, symbols [ 2011-Sep-14 20:44:57.680345 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,FMD,1*3E [ 2011-Sep-14 20:44:57.680821 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: PSK FM probe direction,0 up, 1 down [ 2011-Sep-14 20:44:57.681745 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,GPS,0*34 [ 2011-Sep-14 20:44:57.682255 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: GPS parser on aux. serial port [ 2011-Sep-14 20:44:57.682918 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,HFC,0*3D [ 2011-Sep-14 20:44:57.683405 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Hardware flow control on main serial port [ 2011-Sep-14 20:44:57.684088 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,IRE,0*2E [ 2011-Sep-14 20:44:57.684583 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Print impulse response of FM sweep [ 2011-Sep-14 20:44:57.685266 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,MOD,0*36 [ 2011-Sep-14 20:44:57.685735 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: 0 sends FSK minipacket, 1 sends PSK minipacket [ 2011-Sep-14 20:44:57.686436 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,MFD,0*3F [ 2011-Sep-14 20:44:57.686917 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the MFD messages [ 2011-Sep-14 20:44:57.687573 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,MSE,0*2B [ 2011-Sep-14 20:44:57.688053 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Print symbol mean squared error (dB) from the LMS equalizer [ 2011-Sep-14 20:44:57.688712 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,MCM,1*32 [ 2011-Sep-14 20:44:57.689217 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Enable current mode hydrophone power supply on Rev. C Multi-Channel Analog Board. Must be set to 1 for Rev. B Multi-Channel Analog Board. [ 2011-Sep-14 20:44:57.689870 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,MPR,1*3E [ 2011-Sep-14 20:44:57.690387 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Enable power toggling on Multi-Channel Analog Board [ 2011-Sep-14 20:44:57.795793 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,MVM,1*27 [ 2011-Sep-14 20:44:57.796345 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Enable voltage mode hydrophone power supply on Multi-Channel Analog Board [ 2011-Sep-14 20:44:57.797033 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,NDT,120*2D [ 2011-Sep-14 20:44:57.797522 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Detect threshold for nav detector [ 2011-Sep-14 20:44:57.798213 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,NPT,50*0F [ 2011-Sep-14 20:44:57.798682 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Power threshold for nav detector [ 2011-Sep-14 20:44:57.799363 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,NRL,25*17 [ 2011-Sep-14 20:44:57.799853 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Navigation reverb lockout (ms) [ 2011-Sep-14 20:44:57.800515 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,NRV,150*3E [ 2011-Sep-14 20:44:57.801053 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Number of CTOs before hard reboot [ 2011-Sep-14 20:44:57.801959 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,PAD,2*27 [ 2011-Sep-14 20:44:57.802455 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Power-amp delay (ms) [ 2011-Sep-14 20:44:57.803112 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,PCM,0*2E [ 2011-Sep-14 20:44:57.803600 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Passband channel mask [ 2011-Sep-14 20:44:57.804294 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,PRL,50*0B [ 2011-Sep-14 20:44:57.804764 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.805462 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,PTH,50*09 [ 2011-Sep-14 20:44:57.805954 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Matched filter detector power threshold [ 2011-Sep-14 20:44:57.806624 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,POW,-100*14 [ 2011-Sep-14 20:44:57.807113 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Detection power threshold (dB) PRL Int Packet reverb lockout (ms) [ 2011-Sep-14 20:44:57.807841 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,PTO,14*0E [ 2011-Sep-14 20:44:57.808338 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Packet timeout (sec) [ 2011-Sep-14 20:44:57.809033 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,REV,1*30 [ 2011-Sep-14 20:44:57.809526 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CAREV message [ 2011-Sep-14 20:44:57.810225 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,RSP,-157*0F [ 2011-Sep-14 20:44:57.810704 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.811316 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,RXA,0*3B [ 2011-Sep-14 20:44:57.811446 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXA message [ 2011-Sep-14 20:44:57.916032 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,RXD,1*3F [ 2011-Sep-14 20:44:57.916552 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXD message [ 2011-Sep-14 20:44:57.917257 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,RXP,1*2B [ 2011-Sep-14 20:44:57.917729 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXP message [ 2011-Sep-14 20:44:57.918456 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SCG,0*27 [ 2011-Sep-14 20:44:57.918944 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Set clock from GPS [ 2011-Sep-14 20:44:57.919602 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SGP,1*35 [ 2011-Sep-14 20:44:57.920081 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Show GPS messages on main serial port [ 2011-Sep-14 20:44:57.920738 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SHF,0*2D [ 2011-Sep-14 20:44:57.921356 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CASHF message [ 2011-Sep-14 20:44:57.922103 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SNR,0*3F [ 2011-Sep-14 20:44:57.922605 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on SNR stats for PSK comms [ 2011-Sep-14 20:44:57.923295 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SNV,0*3B [ 2011-Sep-14 20:44:57.923759 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Synchronous transmission of packets [ 2011-Sep-14 20:44:57.924439 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,SRC,1*33 [ 2011-Sep-14 20:44:57.924921 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Default Source Address [ 2011-Sep-14 20:44:57.925591 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,TAT,50*04 [ 2011-Sep-14 20:44:57.926078 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Navigation turn-around-time (msec) [ 2011-Sep-14 20:44:57.926737 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,TOA,0*2A [ 2011-Sep-14 20:44:57.927238 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Display time of arrival of a packet (sec) [ 2011-Sep-14 20:44:57.927970 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,TXD,600*3E [ 2011-Sep-14 20:44:57.928455 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Delay before transmit (ms) [ 2011-Sep-14 20:44:57.929111 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,TXP,1*2D [ 2011-Sep-14 20:44:57.929593 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on start of transmit message [ 2011-Sep-14 20:44:58.035556 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,TXF,1*3B [ 2011-Sep-14 20:44:58.036061 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on end of transmit message [ 2011-Sep-14 20:44:58.036721 ] {goby::acomms::modemdriver::in::1}: D: $CACFG,XST,1*2E [ 2011-Sep-14 20:44:58.037185 ] {goby::acomms::modemdriver::in::1}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on transmit stats message, CAXST [ 2011-Sep-14 20:44:59.140574 ] {test}: Ping test [ 2011-Sep-14 20:44:59.141408 ] {driver1}: Can modify: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:44:59.141873 ] {driver1}: dest: 2 [ 2011-Sep-14 20:44:59.142356 ] {driver1}: type: MICROMODEM_TWO_WAY_PING [ 2011-Sep-14 20:44:59.142807 ] {driver1}: [ 2011-Sep-14 20:44:59.143615 ] {goby::acomms::modemdriver::out::1}: D: $CCMPC,1,2*5D [ 2011-Sep-14 20:44:59.144081 ] {goby::acomms::modemdriver::out::1}: ^ Mini-Packet Ping command, host to modem [ 2011-Sep-14 20:44:59.245383 ] {goby::acomms::modemdriver::in::1}: D: $CAMPC,1,2*5F [ 2011-Sep-14 20:44:59.245898 ] {goby::acomms::modemdriver::in::1}: ^ Echo of Ping command, modem to host [ 2011-Sep-14 20:44:59.246527 ] {goby::acomms::modemdriver::in::1}: D: $CATXP,0*42 [ 2011-Sep-14 20:44:59.246994 ] {goby::acomms::modemdriver::in::1}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:00.150322 ] {goby::acomms::modemdriver::in::1}: D: $CATXF,0*54 [ 2011-Sep-14 20:45:00.150827 ] {goby::acomms::modemdriver::in::1}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:00.151529 ] {goby::acomms::modemdriver::in::1}: D: $CAXST,20110914,204459.0000,1,0,040,4000,25120,0,001,002,0,1,1,2,0000*6B [ 2011-Sep-14 20:45:00.151992 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:00.152930 ] {driver1}: Completed transmit: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:00.153387 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:00.153898 ] {driver1}: type: MICROMODEM_TWO_WAY_PING [ 2011-Sep-14 20:45:00.154354 ] {driver1}: [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:00.154808 ] {driver1}: date: "20110914" [ 2011-Sep-14 20:45:00.155261 ] {driver1}: time: "204459.0000" [ 2011-Sep-14 20:45:00.155715 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:00.156167 ] {driver1}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:00.156620 ] {driver1}: probe_length: 40 [ 2011-Sep-14 20:45:00.157074 ] {driver1}: bandwidth: 4000 [ 2011-Sep-14 20:45:00.157528 ] {driver1}: carrier_freq: 25120 [ 2011-Sep-14 20:45:00.157980 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:00.158433 ] {driver1}: source: 1 [ 2011-Sep-14 20:45:00.158885 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:00.159337 ] {driver1}: ack_requested: false [ 2011-Sep-14 20:45:00.159790 ] {driver1}: number_frames_expected: 1 [ 2011-Sep-14 20:45:00.160252 ] {driver1}: number_frames_sent: 1 [ 2011-Sep-14 20:45:00.160705 ] {driver1}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:00.161159 ] {driver1}: number_bytes: 0 [ 2011-Sep-14 20:45:00.161617 ] {driver1}: } [ 2011-Sep-14 20:45:00.162070 ] {driver1}: [ 2011-Sep-14 20:45:00.163740 ] {}: D: time is: 2011-Sep-14 20:44:59 [ 2011-Sep-14 20:45:00.269021 ] {goby::acomms::modemdriver::in::1}: D: $CAREV,204500,AUV,0.94.0.00*0D [ 2011-Sep-14 20:45:00.269514 ] {goby::acomms::modemdriver::in::1}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:00.270224 ] {goby::acomms::modemdriver::in::1}: D: $CAREV,204500,COPROC,0.10.0.46*43 [ 2011-Sep-14 20:45:00.270685 ] {goby::acomms::modemdriver::in::1}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:01.273786 ] {goby::acomms::modemdriver::in::1}: D: $CARXP,0*44 [ 2011-Sep-14 20:45:01.273940 ] {goby::acomms::modemdriver::in::1}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:02.177128 ] {goby::acomms::modemdriver::in::1}: D: $CADQF,250,2*54 [ 2011-Sep-14 20:45:02.177644 ] {goby::acomms::modemdriver::in::1}: ^ Data quality factor information, modem to host [ 2011-Sep-14 20:45:02.178286 ] {goby::acomms::modemdriver::in::1}: D: $CAMPR,2,1,0.0001*7D [ 2011-Sep-14 20:45:02.178746 ] {goby::acomms::modemdriver::in::1}: ^ Reply to Ping has been received, modem to host [ 2011-Sep-14 20:45:02.290889 ] {goby::acomms::modemdriver::in::1}: D: $CACST,0,204501.0000,1,9372,21,0399,0172,00,01,01,03,02,0,002,001,0,2,1,0,-01,-1,-1,-1,00,250,0.0,-1,25120,4000*78 [ 2011-Sep-14 20:45:02.291393 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:02.292116 ] {}: D: time is: 2011-Sep-14 20:45:01 [ 2011-Sep-14 20:45:02.292856 ] {driver1}: Received: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:02.293315 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:02.293775 ] {driver1}: time: 1316033101000000 [ 2011-Sep-14 20:45:02.294231 ] {driver1}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:02.294682 ] {driver1}: type: MICROMODEM_TWO_WAY_PING [ 2011-Sep-14 20:45:02.295135 ] {driver1}: [micromodem.protobuf.ranging_reply] { [ 2011-Sep-14 20:45:02.295587 ] {driver1}: one_way_travel_time: 0.0001 [ 2011-Sep-14 20:45:02.296054 ] {driver1}: } [ 2011-Sep-14 20:45:02.296507 ] {driver1}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:02.296959 ] {driver1}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:02.297411 ] {driver1}: time: "204501.0000" [ 2011-Sep-14 20:45:02.297864 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:02.298315 ] {driver1}: mfd_peak: 9372 [ 2011-Sep-14 20:45:02.298767 ] {driver1}: mfd_power: 21 [ 2011-Sep-14 20:45:02.299219 ] {driver1}: mfd_ratio: 399 [ 2011-Sep-14 20:45:02.299670 ] {driver1}: spl: 172 [ 2011-Sep-14 20:45:02.300121 ] {driver1}: shf_agn: 0 [ 2011-Sep-14 20:45:02.300576 ] {driver1}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:02.301035 ] {driver1}: shf_ainshift: 1 [ 2011-Sep-14 20:45:02.301486 ] {driver1}: shf_mfdshift: 3 [ 2011-Sep-14 20:45:02.301938 ] {driver1}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:02.302457 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:02.302578 ] {driver1}: source: 2 [ 2011-Sep-14 20:45:02.302698 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:02.302818 ] {driver1}: psk_error_code: 0 [ 2011-Sep-14 20:45:02.302938 ] {driver1}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:02.303059 ] {driver1}: number_frames: 1 [ 2011-Sep-14 20:45:02.303179 ] {driver1}: number_bad_frames: 0 [ 2011-Sep-14 20:45:02.303299 ] {driver1}: snr_rss: -1 [ 2011-Sep-14 20:45:02.303420 ] {driver1}: snr_in: -1 [ 2011-Sep-14 20:45:02.303540 ] {driver1}: snr_out: -1 [ 2011-Sep-14 20:45:02.303660 ] {driver1}: snr_symbols: -1 [ 2011-Sep-14 20:45:02.303780 ] {driver1}: mse_equalizer: 0 [ 2011-Sep-14 20:45:02.303899 ] {driver1}: data_quality_factor: 250 [ 2011-Sep-14 20:45:02.304020 ] {driver1}: doppler: 0 [ 2011-Sep-14 20:45:02.304139 ] {driver1}: stddev_noise: -1 [ 2011-Sep-14 20:45:02.304258 ] {driver1}: carrier_freq: 25120 [ 2011-Sep-14 20:45:02.304378 ] {driver1}: bandwidth: 4000 [ 2011-Sep-14 20:45:02.304498 ] {driver1}: version: 0 [ 2011-Sep-14 20:45:02.304618 ] {driver1}: } [ 2011-Sep-14 20:45:02.304738 ] {driver1}: [ 2011-Sep-14 20:45:02.406017 ] {test}: Test 0 passed. [ 2011-Sep-14 20:45:03.406926 ] {test}: Remus LBL test [ 2011-Sep-14 20:45:03.407668 ] {driver1}: Can modify: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:03.408133 ] {driver1}: type: MICROMODEM_REMUS_LBL_RANGING [ 2011-Sep-14 20:45:03.408596 ] {driver1}: [micromodem.protobuf.remus_lbl] { [ 2011-Sep-14 20:45:03.409052 ] {driver1}: lbl_max_range: 1000 [ 2011-Sep-14 20:45:03.409512 ] {driver1}: } [ 2011-Sep-14 20:45:03.409967 ] {driver1}: [ 2011-Sep-14 20:45:03.411074 ] {goby::acomms::modemdriver::out::1}: D: $CCPDT,1,2,0,0,1383,1,1,1,0*67 [ 2011-Sep-14 20:45:03.411320 ] {goby::acomms::modemdriver::out::1}: ^ Ping REMUS digital transponder, host to modem [ 2011-Sep-14 20:45:03.513067 ] {goby::acomms::modemdriver::in::1}: D: $SNPDT,1,2,0,0,1383,1,1,1,0*7A [ 2011-Sep-14 20:45:03.513571 ] {goby::acomms::modemdriver::in::1}: ^ [ 2011-Sep-14 20:45:04.916493 ] {goby::acomms::modemdriver::in::1}: D: $SNTTA,,,,,204504.00*59 [ 2011-Sep-14 20:45:04.916990 ] {goby::acomms::modemdriver::in::1}: ^ Transponder travel times, modem to host [ 2011-Sep-14 20:45:04.918150 ] {driver1}: Received: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:04.918610 ] {driver1}: time: 1316033104000000 [ 2011-Sep-14 20:45:04.919067 ] {driver1}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:04.919522 ] {driver1}: type: MICROMODEM_REMUS_LBL_RANGING [ 2011-Sep-14 20:45:04.919977 ] {driver1}: [micromodem.protobuf.ranging_reply] { [ 2011-Sep-14 20:45:04.920433 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:04.920889 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:04.921348 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:04.921804 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:04.922320 ] {driver1}: } [ 2011-Sep-14 20:45:04.922802 ] {driver1}: [ 2011-Sep-14 20:45:05.023673 ] {test}: Test 1 passed. [ 2011-Sep-14 20:45:06.024608 ] {test}: Narrowband LBL test [ 2011-Sep-14 20:45:06.025329 ] {driver1}: Can modify: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:06.025791 ] {driver1}: type: MICROMODEM_NARROWBAND_LBL_RANGING [ 2011-Sep-14 20:45:06.026247 ] {driver1}: [micromodem.protobuf.narrowband_lbl] { [ 2011-Sep-14 20:45:06.026703 ] {driver1}: transmit_freq: 26000 [ 2011-Sep-14 20:45:06.027157 ] {driver1}: transmit_ping_ms: 5 [ 2011-Sep-14 20:45:06.027610 ] {driver1}: receive_freq: 25000 [ 2011-Sep-14 20:45:06.028065 ] {driver1}: receive_ping_ms: 5 [ 2011-Sep-14 20:45:06.028519 ] {driver1}: turnaround_ms: 20 [ 2011-Sep-14 20:45:06.028972 ] {driver1}: transmit_flag: true [ 2011-Sep-14 20:45:06.029426 ] {driver1}: lbl_max_range: 1000 [ 2011-Sep-14 20:45:06.029879 ] {driver1}: } [ 2011-Sep-14 20:45:06.030327 ] {driver1}: [ 2011-Sep-14 20:45:06.031393 ] {goby::acomms::modemdriver::out::1}: D: $CCPNT,26000,5,5,1353,25000,0,0,0,1*60 [ 2011-Sep-14 20:45:06.031863 ] {goby::acomms::modemdriver::out::1}: ^ Ping narrowband transponder, host to modem [ 2011-Sep-14 20:45:06.133269 ] {goby::acomms::modemdriver::in::1}: D: $SNPNT,26000,5,5,1353,25000,0,0,0*60 [ 2011-Sep-14 20:45:06.133766 ] {goby::acomms::modemdriver::in::1}: ^ [ 2011-Sep-14 20:45:07.536996 ] {goby::acomms::modemdriver::in::1}: D: $SNTTA,,,,,204507.00*5A [ 2011-Sep-14 20:45:07.537503 ] {goby::acomms::modemdriver::in::1}: ^ Transponder travel times, modem to host [ 2011-Sep-14 20:45:07.538337 ] {driver1}: Received: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:07.538794 ] {driver1}: time: 1316033107000000 [ 2011-Sep-14 20:45:07.539248 ] {driver1}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:07.539702 ] {driver1}: type: MICROMODEM_NARROWBAND_LBL_RANGING [ 2011-Sep-14 20:45:07.540155 ] {driver1}: [micromodem.protobuf.ranging_reply] { [ 2011-Sep-14 20:45:07.540608 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:07.541062 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:07.541517 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:07.541969 ] {driver1}: one_way_travel_time: nan [ 2011-Sep-14 20:45:07.542483 ] {driver1}: } [ 2011-Sep-14 20:45:07.542931 ] {driver1}: [ 2011-Sep-14 20:45:07.643699 ] {test}: Test 2 passed. [ 2011-Sep-14 20:45:08.644547 ] {test}: Mini data test [ 2011-Sep-14 20:45:08.857389 ] {goby::acomms::modemdriver::in::1}: D: $CARXP,0*44 [ 2011-Sep-14 20:45:08.857874 ] {goby::acomms::modemdriver::in::1}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:09.659932 ] {goby::acomms::modemdriver::in::1}: D: $CADQF,250,2*54 [ 2011-Sep-14 20:45:09.660438 ] {goby::acomms::modemdriver::in::1}: ^ Data quality factor information, modem to host [ 2011-Sep-14 20:45:09.661078 ] {goby::acomms::modemdriver::in::1}: D: $CAMUA,2,1,0123*74 [ 2011-Sep-14 20:45:09.661550 ] {goby::acomms::modemdriver::in::1}: ^ Mini-Packet received acoustically, modem to host [ 2011-Sep-14 20:45:09.774077 ] {goby::acomms::modemdriver::in::1}: D: $CACST,0,204508.0000,1,2328,21,0415,0171,00,01,01,02,02,0,002,001,0,2,1,0,-01,-1,-1,-1,00,250,0.0,-1,25120,4000*74 [ 2011-Sep-14 20:45:09.774574 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:09.775307 ] {}: D: time is: 2011-Sep-14 20:45:08 [ 2011-Sep-14 20:45:09.775963 ] {driver1}: Received: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:09.776419 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:09.776869 ] {driver1}: time: 1316033108000000 [ 2011-Sep-14 20:45:09.777319 ] {driver1}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:09.777768 ] {driver1}: type: MICROMODEM_MINI_DATA [ 2011-Sep-14 20:45:09.778224 ] {driver1}: frame: "\001#" [ 2011-Sep-14 20:45:09.778676 ] {driver1}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:09.779126 ] {driver1}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:09.779575 ] {driver1}: time: "204508.0000" [ 2011-Sep-14 20:45:09.780027 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:09.780476 ] {driver1}: mfd_peak: 2328 [ 2011-Sep-14 20:45:09.780924 ] {driver1}: mfd_power: 21 [ 2011-Sep-14 20:45:09.781372 ] {driver1}: mfd_ratio: 415 [ 2011-Sep-14 20:45:09.781821 ] {driver1}: spl: 171 [ 2011-Sep-14 20:45:09.782343 ] {driver1}: shf_agn: 0 [ 2011-Sep-14 20:45:09.782800 ] {driver1}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:09.783248 ] {driver1}: shf_ainshift: 1 [ 2011-Sep-14 20:45:09.783700 ] {driver1}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:09.784152 ] {driver1}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:09.784598 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:09.785045 ] {driver1}: source: 2 [ 2011-Sep-14 20:45:09.785491 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:09.785938 ] {driver1}: psk_error_code: 0 [ 2011-Sep-14 20:45:09.786386 ] {driver1}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:09.786833 ] {driver1}: number_frames: 1 [ 2011-Sep-14 20:45:09.787280 ] {driver1}: number_bad_frames: 0 [ 2011-Sep-14 20:45:09.787728 ] {driver1}: snr_rss: -1 [ 2011-Sep-14 20:45:09.788176 ] {driver1}: snr_in: -1 [ 2011-Sep-14 20:45:09.788623 ] {driver1}: snr_out: -1 [ 2011-Sep-14 20:45:09.789070 ] {driver1}: snr_symbols: -1 [ 2011-Sep-14 20:45:09.789518 ] {driver1}: mse_equalizer: 0 [ 2011-Sep-14 20:45:09.789971 ] {driver1}: data_quality_factor: 250 [ 2011-Sep-14 20:45:09.790426 ] {driver1}: doppler: 0 [ 2011-Sep-14 20:45:09.790880 ] {driver1}: stddev_noise: -1 [ 2011-Sep-14 20:45:09.791339 ] {driver1}: carrier_freq: 25120 [ 2011-Sep-14 20:45:09.791794 ] {driver1}: bandwidth: 4000 [ 2011-Sep-14 20:45:09.792305 ] {driver1}: version: 0 [ 2011-Sep-14 20:45:09.792535 ] {driver1}: } [ 2011-Sep-14 20:45:09.792652 ] {driver1}: [ 2011-Sep-14 20:45:09.894011 ] {test}: Test 3 passed. [ 2011-Sep-14 20:45:10.894909 ] {test}: Rate 0 test [ 2011-Sep-14 20:45:10.895541 ] {driver1}: Can modify: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:10.895996 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:10.896446 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:10.896897 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:10.897346 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:10.897792 ] {driver1}: [ 2011-Sep-14 20:45:10.898339 ] {driver1}: Data request: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:10.898792 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:10.899242 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:10.899692 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:10.900141 ] {driver1}: max_num_frames: 1 [ 2011-Sep-14 20:45:10.900591 ] {driver1}: max_frame_bytes: 32 [ 2011-Sep-14 20:45:10.901041 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:10.901490 ] {driver1}: [ 2011-Sep-14 20:45:10.902000 ] {driver1}: Post data request: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:10.902538 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:10.902990 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:10.903440 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:10.903890 ] {driver1}: max_num_frames: 1 [ 2011-Sep-14 20:45:10.904340 ] {driver1}: max_frame_bytes: 32 [ 2011-Sep-14 20:45:10.904802 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:10.905252 ] {driver1}: frame: "55555555555555555555555555555555" [ 2011-Sep-14 20:45:10.905697 ] {driver1}: [ 2011-Sep-14 20:45:10.906571 ] {goby::acomms::modemdriver::out::1}: D: $CCCYC,0,1,2,0,1,1*5A [ 2011-Sep-14 20:45:10.907030 ] {goby::acomms::modemdriver::out::1}: ^ Network Cycle Initialization Command [ 2011-Sep-14 20:45:11.008017 ] {goby::acomms::modemdriver::in::1}: D: $CACYC,0,1,2,0,1,1*58 [ 2011-Sep-14 20:45:11.008514 ] {goby::acomms::modemdriver::in::1}: ^ Echo of Network Cycle Initialization command [ 2011-Sep-14 20:45:11.009159 ] {goby::acomms::modemdriver::in::1}: D: $CATXP,0*42 [ 2011-Sep-14 20:45:11.009620 ] {goby::acomms::modemdriver::in::1}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:11.913000 ] {goby::acomms::modemdriver::in::1}: D: $CATXF,0*54 [ 2011-Sep-14 20:45:11.913512 ] {goby::acomms::modemdriver::in::1}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:11.914288 ] {goby::acomms::modemdriver::in::1}: D: $CAXST,20110914,204510.0000,1,0,040,4000,25120,0,001,002,1,1,1,2,0000*66 [ 2011-Sep-14 20:45:11.914747 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:11.915452 ] {goby::acomms::modemdriver::in::1}: D: $CADRQ,204511,1,2,1,32,1*44 [ 2011-Sep-14 20:45:11.915915 ] {goby::acomms::modemdriver::in::1}: ^ Data request message, modem to host [ 2011-Sep-14 20:45:11.916747 ] {goby::acomms::modemdriver::out::1}: D: $CCTXD,1,2,1,3535353535353535353535353535353535353535353535353535353535353535*7A [ 2011-Sep-14 20:45:11.917212 ] {goby::acomms::modemdriver::out::1}: ^ Transmit binary data message, host to modem [ 2011-Sep-14 20:45:12.021873 ] {}: D: time is: 2011-Sep-14 20:45:10 [ 2011-Sep-14 20:45:12.523423 ] {goby::acomms::modemdriver::in::1}: D: $CATXD,1,2,1,32*79 [ 2011-Sep-14 20:45:12.523571 ] {goby::acomms::modemdriver::in::1}: ^ Echo back of transmit binary data message [ 2011-Sep-14 20:45:12.624273 ] {goby::acomms::modemdriver::in::1}: D: $CATXP,32*73 [ 2011-Sep-14 20:45:12.624763 ] {goby::acomms::modemdriver::in::1}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:16.732961 ] {goby::acomms::modemdriver::in::1}: D: $CATXF,32*65 [ 2011-Sep-14 20:45:16.733474 ] {goby::acomms::modemdriver::in::1}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:16.834461 ] {goby::acomms::modemdriver::in::1}: D: $CAXST,20110914,204512.0000,1,0,040,4000,25120,0,001,002,1,1,1,1,0032*66 [ 2011-Sep-14 20:45:16.834954 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:16.835656 ] {driver1}: Completed transmit: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:16.836113 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:16.836566 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:16.837017 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:16.837468 ] {driver1}: max_num_frames: 1 [ 2011-Sep-14 20:45:16.837919 ] {driver1}: max_frame_bytes: 32 [ 2011-Sep-14 20:45:16.838370 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:16.838821 ] {driver1}: frame: "55555555555555555555555555555555" [ 2011-Sep-14 20:45:16.839274 ] {driver1}: [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:16.839725 ] {driver1}: date: "20110914" [ 2011-Sep-14 20:45:16.840177 ] {driver1}: time: "204510.0000" [ 2011-Sep-14 20:45:16.840629 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:16.841080 ] {driver1}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:16.841531 ] {driver1}: probe_length: 40 [ 2011-Sep-14 20:45:16.841982 ] {driver1}: bandwidth: 4000 [ 2011-Sep-14 20:45:16.842799 ] {driver1}: carrier_freq: 25120 [ 2011-Sep-14 20:45:16.843255 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:16.843705 ] {driver1}: source: 1 [ 2011-Sep-14 20:45:16.844158 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:16.844615 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:16.845066 ] {driver1}: number_frames_expected: 1 [ 2011-Sep-14 20:45:16.845516 ] {driver1}: number_frames_sent: 1 [ 2011-Sep-14 20:45:16.845966 ] {driver1}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:16.846416 ] {driver1}: number_bytes: 0 [ 2011-Sep-14 20:45:16.846865 ] {driver1}: } [ 2011-Sep-14 20:45:16.847316 ] {driver1}: [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:16.847765 ] {driver1}: date: "20110914" [ 2011-Sep-14 20:45:16.848216 ] {driver1}: time: "204512.0000" [ 2011-Sep-14 20:45:16.848666 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:16.849116 ] {driver1}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:16.849567 ] {driver1}: probe_length: 40 [ 2011-Sep-14 20:45:16.850015 ] {driver1}: bandwidth: 4000 [ 2011-Sep-14 20:45:16.850465 ] {driver1}: carrier_freq: 25120 [ 2011-Sep-14 20:45:16.850913 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:16.851363 ] {driver1}: source: 1 [ 2011-Sep-14 20:45:16.851816 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:16.852321 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:16.852551 ] {driver1}: number_frames_expected: 1 [ 2011-Sep-14 20:45:16.852672 ] {driver1}: number_frames_sent: 1 [ 2011-Sep-14 20:45:16.852792 ] {driver1}: packet_type: FSK [ 2011-Sep-14 20:45:16.852910 ] {driver1}: number_bytes: 32 [ 2011-Sep-14 20:45:16.853029 ] {driver1}: } [ 2011-Sep-14 20:45:16.853147 ] {driver1}: [ 2011-Sep-14 20:45:16.853323 ] {goby::acomms::modemdriver::in::1}: D: $CAREV,204516,AUV,0.94.0.00*0A [ 2011-Sep-14 20:45:16.853445 ] {goby::acomms::modemdriver::in::1}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:16.853627 ] {goby::acomms::modemdriver::in::1}: D: $CAREV,204516,COPROC,0.10.0.46*44 [ 2011-Sep-14 20:45:16.853748 ] {goby::acomms::modemdriver::in::1}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:16.956444 ] {}: D: time is: 2011-Sep-14 20:45:12 [ 2011-Sep-14 20:45:17.179664 ] {goby::acomms::modemdriver::in::1}: D: $CARXP,0*44 [ 2011-Sep-14 20:45:17.180156 ] {goby::acomms::modemdriver::in::1}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:17.982395 ] {goby::acomms::modemdriver::in::1}: D: $CADQF,252,2*56 [ 2011-Sep-14 20:45:17.982897 ] {goby::acomms::modemdriver::in::1}: ^ Data quality factor information, modem to host [ 2011-Sep-14 20:45:17.983537 ] {goby::acomms::modemdriver::in::1}: D: $CAACK,2,1,1,1*48 [ 2011-Sep-14 20:45:17.984003 ] {goby::acomms::modemdriver::in::1}: ^ Acknowledgment of a transmitted packet [ 2011-Sep-14 20:45:18.096087 ] {goby::acomms::modemdriver::in::1}: D: $CACST,0,204517.0000,1,2157,21,0370,0170,00,01,01,02,02,0,002,001,0,2,1,0,-01,-1,-1,-1,00,252,0.0,-1,25120,4000*77 [ 2011-Sep-14 20:45:18.096589 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:18.097308 ] {}: D: time is: 2011-Sep-14 20:45:17 [ 2011-Sep-14 20:45:18.097964 ] {driver1}: Received: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:18.098425 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:18.098880 ] {driver1}: time: 1316033117000000 [ 2011-Sep-14 20:45:18.099335 ] {driver1}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:18.099789 ] {driver1}: type: ACK [ 2011-Sep-14 20:45:18.100243 ] {driver1}: acked_frame: 0 [ 2011-Sep-14 20:45:18.100701 ] {driver1}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:18.101326 ] {driver1}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:18.101786 ] {driver1}: time: "204517.0000" [ 2011-Sep-14 20:45:18.102280 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:18.102736 ] {driver1}: mfd_peak: 2157 [ 2011-Sep-14 20:45:18.103191 ] {driver1}: mfd_power: 21 [ 2011-Sep-14 20:45:18.103644 ] {driver1}: mfd_ratio: 370 [ 2011-Sep-14 20:45:18.104098 ] {driver1}: spl: 170 [ 2011-Sep-14 20:45:18.104569 ] {driver1}: shf_agn: 0 [ 2011-Sep-14 20:45:18.105025 ] {driver1}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:18.105508 ] {driver1}: shf_ainshift: 1 [ 2011-Sep-14 20:45:18.105971 ] {driver1}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:18.106429 ] {driver1}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:18.106883 ] {driver1}: rate: 0 [ 2011-Sep-14 20:45:18.107339 ] {driver1}: source: 2 [ 2011-Sep-14 20:45:18.107793 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:18.108249 ] {driver1}: psk_error_code: 0 [ 2011-Sep-14 20:45:18.108704 ] {driver1}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:18.109158 ] {driver1}: number_frames: 1 [ 2011-Sep-14 20:45:18.109616 ] {driver1}: number_bad_frames: 0 [ 2011-Sep-14 20:45:18.110073 ] {driver1}: snr_rss: -1 [ 2011-Sep-14 20:45:18.110533 ] {driver1}: snr_in: -1 [ 2011-Sep-14 20:45:18.110993 ] {driver1}: snr_out: -1 [ 2011-Sep-14 20:45:18.111455 ] {driver1}: snr_symbols: -1 [ 2011-Sep-14 20:45:18.111915 ] {driver1}: mse_equalizer: 0 [ 2011-Sep-14 20:45:18.112437 ] {driver1}: data_quality_factor: 252 [ 2011-Sep-14 20:45:18.112569 ] {driver1}: doppler: 0 [ 2011-Sep-14 20:45:18.112689 ] {driver1}: stddev_noise: -1 [ 2011-Sep-14 20:45:18.112810 ] {driver1}: carrier_freq: 25120 [ 2011-Sep-14 20:45:18.112930 ] {driver1}: bandwidth: 4000 [ 2011-Sep-14 20:45:18.113051 ] {driver1}: version: 0 [ 2011-Sep-14 20:45:18.113172 ] {driver1}: } [ 2011-Sep-14 20:45:18.113293 ] {driver1}: [ 2011-Sep-14 20:45:18.214597 ] {test}: Test 4 passed. [ 2011-Sep-14 20:45:19.215532 ] {test}: Rate 2 test [ 2011-Sep-14 20:45:19.216178 ] {driver1}: Can modify: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:19.216636 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:19.217087 ] {driver1}: rate: 2 [ 2011-Sep-14 20:45:19.217538 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:19.217989 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:19.218442 ] {driver1}: frame: "1111111111111111111111111111111111111111111111111111111111111111" [ 2011-Sep-14 20:45:19.218889 ] {driver1}: [ 2011-Sep-14 20:45:19.219412 ] {driver1}: Data request: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:19.219864 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:19.220314 ] {driver1}: rate: 2 [ 2011-Sep-14 20:45:19.220765 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:19.221219 ] {driver1}: max_num_frames: 3 [ 2011-Sep-14 20:45:19.221670 ] {driver1}: max_frame_bytes: 64 [ 2011-Sep-14 20:45:19.222121 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:19.222637 ] {driver1}: frame: "1111111111111111111111111111111111111111111111111111111111111111" [ 2011-Sep-14 20:45:19.223084 ] {driver1}: [ 2011-Sep-14 20:45:19.223579 ] {driver1}: Post data request: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:19.224031 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:19.224481 ] {driver1}: rate: 2 [ 2011-Sep-14 20:45:19.224931 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:19.225393 ] {driver1}: max_num_frames: 3 [ 2011-Sep-14 20:45:19.225843 ] {driver1}: max_frame_bytes: 64 [ 2011-Sep-14 20:45:19.226293 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:19.226770 ] {driver1}: frame: "1111111111111111111111111111111111111111111111111111111111111111" [ 2011-Sep-14 20:45:19.227221 ] {driver1}: frame: "2222222222222222222222222222222222222222222222222222222222222222" [ 2011-Sep-14 20:45:19.227666 ] {driver1}: [ 2011-Sep-14 20:45:19.228530 ] {goby::acomms::modemdriver::out::1}: D: $CCCYC,0,1,2,2,1,3*5A [ 2011-Sep-14 20:45:19.228987 ] {goby::acomms::modemdriver::out::1}: ^ Network Cycle Initialization Command [ 2011-Sep-14 20:45:19.329994 ] {goby::acomms::modemdriver::in::1}: D: $CACYC,0,1,2,2,1,3*58 [ 2011-Sep-14 20:45:19.330486 ] {goby::acomms::modemdriver::in::1}: ^ Echo of Network Cycle Initialization command [ 2011-Sep-14 20:45:19.331148 ] {goby::acomms::modemdriver::in::1}: D: $CADRQ,204519,1,2,1,64,1*4F [ 2011-Sep-14 20:45:19.331611 ] {goby::acomms::modemdriver::in::1}: ^ Data request message, modem to host [ 2011-Sep-14 20:45:19.332468 ] {goby::acomms::modemdriver::out::1}: D: $CCTXD,1,2,1,31313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131*7A [ 2011-Sep-14 20:45:19.332934 ] {goby::acomms::modemdriver::out::1}: ^ Transmit binary data message, host to modem [ 2011-Sep-14 20:45:19.534027 ] {goby::acomms::modemdriver::in::1}: D: $CATXD,1,2,1,64*7A [ 2011-Sep-14 20:45:19.534532 ] {goby::acomms::modemdriver::in::1}: ^ Echo back of transmit binary data message [ 2011-Sep-14 20:45:19.535194 ] {goby::acomms::modemdriver::in::1}: D: $CADRQ,204519,1,2,1,64,2*4C [ 2011-Sep-14 20:45:19.535669 ] {goby::acomms::modemdriver::in::1}: ^ Data request message, modem to host [ 2011-Sep-14 20:45:19.536479 ] {goby::acomms::modemdriver::out::1}: D: $CCTXD,1,2,1,32323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232*7A [ 2011-Sep-14 20:45:19.536945 ] {goby::acomms::modemdriver::out::1}: ^ Transmit binary data message, host to modem [ 2011-Sep-14 20:45:19.738256 ] {goby::acomms::modemdriver::in::1}: D: $CATXD,1,2,1,64*7A [ 2011-Sep-14 20:45:19.738753 ] {goby::acomms::modemdriver::in::1}: ^ Echo back of transmit binary data message [ 2011-Sep-14 20:45:19.739406 ] {goby::acomms::modemdriver::in::1}: D: $CADRQ,204519,1,2,1,64,3*4D [ 2011-Sep-14 20:45:19.739874 ] {goby::acomms::modemdriver::in::1}: ^ Data request message, modem to host [ 2011-Sep-14 20:45:19.740628 ] {goby::acomms::modemdriver::out::1}: D: $CCTXD,1,2,1,*7A [ 2011-Sep-14 20:45:19.741097 ] {goby::acomms::modemdriver::out::1}: ^ Transmit binary data message, host to modem [ 2011-Sep-14 20:45:20.042441 ] {goby::acomms::modemdriver::in::1}: D: $CATXD,1,2,1,0*48 [ 2011-Sep-14 20:45:20.042944 ] {goby::acomms::modemdriver::in::1}: ^ Echo back of transmit binary data message [ 2011-Sep-14 20:45:20.043573 ] {goby::acomms::modemdriver::in::1}: D: $CATXP,128*49 [ 2011-Sep-14 20:45:20.044041 ] {goby::acomms::modemdriver::in::1}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:23.451264 ] {goby::acomms::modemdriver::in::1}: D: $CATXF,128*5F [ 2011-Sep-14 20:45:23.451775 ] {goby::acomms::modemdriver::in::1}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:23.452486 ] {goby::acomms::modemdriver::in::1}: D: $CAXST,20110914,204519.0000,1,0,200,5000,25000,2,001,002,1,3,3,3,0128*63 [ 2011-Sep-14 20:45:23.452952 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:23.453649 ] {driver1}: Completed transmit: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:23.454104 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:23.454555 ] {driver1}: rate: 2 [ 2011-Sep-14 20:45:23.455004 ] {driver1}: type: DATA [ 2011-Sep-14 20:45:23.455453 ] {driver1}: max_num_frames: 3 [ 2011-Sep-14 20:45:23.455901 ] {driver1}: max_frame_bytes: 64 [ 2011-Sep-14 20:45:23.456350 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:23.456801 ] {driver1}: frame: "1111111111111111111111111111111111111111111111111111111111111111" [ 2011-Sep-14 20:45:23.457252 ] {driver1}: frame: "2222222222222222222222222222222222222222222222222222222222222222" [ 2011-Sep-14 20:45:23.457702 ] {driver1}: [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:23.458167 ] {driver1}: date: "20110914" [ 2011-Sep-14 20:45:23.458618 ] {driver1}: time: "204519.0000" [ 2011-Sep-14 20:45:23.459067 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:23.459517 ] {driver1}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:23.459966 ] {driver1}: probe_length: 200 [ 2011-Sep-14 20:45:23.460415 ] {driver1}: bandwidth: 5000 [ 2011-Sep-14 20:45:23.460878 ] {driver1}: carrier_freq: 25000 [ 2011-Sep-14 20:45:23.461410 ] {driver1}: rate: 2 [ 2011-Sep-14 20:45:23.461859 ] {driver1}: source: 1 [ 2011-Sep-14 20:45:23.462315 ] {driver1}: dest: 2 [ 2011-Sep-14 20:45:23.462766 ] {driver1}: ack_requested: true [ 2011-Sep-14 20:45:23.463215 ] {driver1}: number_frames_expected: 3 [ 2011-Sep-14 20:45:23.463666 ] {driver1}: number_frames_sent: 3 [ 2011-Sep-14 20:45:23.464116 ] {driver1}: packet_type: PSK [ 2011-Sep-14 20:45:23.464565 ] {driver1}: number_bytes: 128 [ 2011-Sep-14 20:45:23.465014 ] {driver1}: } [ 2011-Sep-14 20:45:23.465459 ] {driver1}: [ 2011-Sep-14 20:45:23.566322 ] {goby::acomms::modemdriver::in::1}: D: $CAREV,204523,AUV,0.94.0.00*0C [ 2011-Sep-14 20:45:23.566813 ] {goby::acomms::modemdriver::in::1}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:23.567490 ] {goby::acomms::modemdriver::in::1}: D: $CAREV,204523,COPROC,0.10.0.46*42 [ 2011-Sep-14 20:45:23.567951 ] {goby::acomms::modemdriver::in::1}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:24.176079 ] {}: D: time is: 2011-Sep-14 20:45:20 [ 2011-Sep-14 20:45:24.596296 ] {goby::acomms::modemdriver::in::1}: D: $CARXP,1*45 [ 2011-Sep-14 20:45:24.596827 ] {goby::acomms::modemdriver::in::1}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:25.300029 ] {goby::acomms::modemdriver::in::1}: D: $CAACK,2,1,1,1*48 [ 2011-Sep-14 20:45:25.300526 ] {goby::acomms::modemdriver::in::1}: ^ Acknowledgment of a transmitted packet [ 2011-Sep-14 20:45:25.301231 ] {goby::acomms::modemdriver::in::1}: D: $CAACK,2,1,2,1*4B [ 2011-Sep-14 20:45:25.301697 ] {goby::acomms::modemdriver::in::1}: ^ Acknowledgment of a transmitted packet [ 2011-Sep-14 20:45:25.302418 ] {goby::acomms::modemdriver::in::1}: D: $CAACK,2,1,3,1*4A [ 2011-Sep-14 20:45:25.302879 ] {goby::acomms::modemdriver::in::1}: ^ Acknowledgment of a transmitted packet [ 2011-Sep-14 20:45:25.303368 ] {}: (Warning): Received acknowledgement for Micro-Modem frame 3 (Goby frame 2) that we were not expecting. [ 2011-Sep-14 20:45:25.413756 ] {goby::acomms::modemdriver::in::1}: D: $CACST,0,204524.0000,1,2185,21,0389,0170,00,01,01,02,02,1,002,001,0,4,1,0,163,72,27,40,-25,000,-0.0,-12,25000,5000*4B [ 2011-Sep-14 20:45:25.414251 ] {goby::acomms::modemdriver::in::1}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:25.414960 ] {}: D: time is: 2011-Sep-14 20:45:24 [ 2011-Sep-14 20:45:25.415610 ] {driver1}: Received: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:25.416072 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:25.416528 ] {driver1}: time: 1316033124000000 [ 2011-Sep-14 20:45:25.416983 ] {driver1}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:25.417438 ] {driver1}: type: ACK [ 2011-Sep-14 20:45:25.417892 ] {driver1}: acked_frame: 0 [ 2011-Sep-14 20:45:25.418347 ] {driver1}: acked_frame: 1 [ 2011-Sep-14 20:45:25.418825 ] {driver1}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:25.419282 ] {driver1}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:25.419736 ] {driver1}: time: "204524.0000" [ 2011-Sep-14 20:45:25.420192 ] {driver1}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:25.420645 ] {driver1}: mfd_peak: 2185 [ 2011-Sep-14 20:45:25.421101 ] {driver1}: mfd_power: 21 [ 2011-Sep-14 20:45:25.421558 ] {driver1}: mfd_ratio: 389 [ 2011-Sep-14 20:45:25.422011 ] {driver1}: spl: 170 [ 2011-Sep-14 20:45:25.422531 ] {driver1}: shf_agn: 0 [ 2011-Sep-14 20:45:25.422986 ] {driver1}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:25.423443 ] {driver1}: shf_ainshift: 1 [ 2011-Sep-14 20:45:25.423903 ] {driver1}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:25.424358 ] {driver1}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:25.424811 ] {driver1}: rate: 1 [ 2011-Sep-14 20:45:25.425265 ] {driver1}: source: 2 [ 2011-Sep-14 20:45:25.425719 ] {driver1}: dest: 1 [ 2011-Sep-14 20:45:25.426173 ] {driver1}: psk_error_code: 0 [ 2011-Sep-14 20:45:25.426627 ] {driver1}: packet_type: PSK_MINI [ 2011-Sep-14 20:45:25.427081 ] {driver1}: number_frames: 1 [ 2011-Sep-14 20:45:25.427534 ] {driver1}: number_bad_frames: 0 [ 2011-Sep-14 20:45:25.427988 ] {driver1}: snr_rss: 163 [ 2011-Sep-14 20:45:25.428443 ] {driver1}: snr_in: 72 [ 2011-Sep-14 20:45:25.428896 ] {driver1}: snr_out: 27 [ 2011-Sep-14 20:45:25.429349 ] {driver1}: snr_symbols: 40 [ 2011-Sep-14 20:45:25.429804 ] {driver1}: mse_equalizer: -25 [ 2011-Sep-14 20:45:25.430258 ] {driver1}: data_quality_factor: 0 [ 2011-Sep-14 20:45:25.430711 ] {driver1}: doppler: -0 [ 2011-Sep-14 20:45:25.431165 ] {driver1}: stddev_noise: -12 [ 2011-Sep-14 20:45:25.431622 ] {driver1}: carrier_freq: 25000 [ 2011-Sep-14 20:45:25.432081 ] {driver1}: bandwidth: 5000 [ 2011-Sep-14 20:45:25.432488 ] {driver1}: version: 0 [ 2011-Sep-14 20:45:25.432608 ] {driver1}: } [ 2011-Sep-14 20:45:25.432728 ] {driver1}: [ 2011-Sep-14 20:45:25.533586 ] {test}: Test 5 passed. [ 2011-Sep-14 20:45:26.534525 ] {test}: all tests passed