[ 2011-Sep-14 20:44:53.279812 ] {}: Adding FlexOstream group: test () [ 2011-Sep-14 20:44:53.280129 ] {}: Adding FlexOstream group: driver2 () [ 2011-Sep-14 20:44:53.281052 ] {}: Adding FlexOstream group: goby::acomms::modemdriver::out::2 () [ 2011-Sep-14 20:44:53.281179 ] {}: Adding FlexOstream group: goby::acomms::modemdriver::in::2 () [ 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:55.006100 ] {goby::acomms::modemdriver::out::2}: D: opening serial port /dev/ttyUSB0 @ 19200 [ 2011-Sep-14 20:44:55.007247 ] {goby::acomms::modemdriver::out::2}: D: $CCCLK,2011,9,14,20,44,55*78 [ 2011-Sep-14 20:44:55.007716 ] {goby::acomms::modemdriver::out::2}: ^ Set clock, host to modem [ 2011-Sep-14 20:44:56.011016 ] {}: D: reported time: 2011-Sep-14 20:44:55 [ 2011-Sep-14 20:44:56.011742 ] {goby::acomms::modemdriver::in::2}: D: $CACLK,2011,09,14,20,44,55*4A [ 2011-Sep-14 20:44:56.012261 ] {goby::acomms::modemdriver::in::2}: ^ Time/Date message, modem to host [ 2011-Sep-14 20:44:56.012882 ] {}: D: reported time: 2011-Sep-14 20:44:56 [ 2011-Sep-14 20:44:56.113831 ] {goby::acomms::modemdriver::out::2}: D: $CCCFG,CST,1*37 [ 2011-Sep-14 20:44:56.113955 ] {goby::acomms::modemdriver::out::2}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.216048 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,CST,1*35 [ 2011-Sep-14 20:44:56.216512 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle statistics message 1 [ 2011-Sep-14 20:44:56.318557 ] {goby::acomms::modemdriver::out::2}: D: $CCCFG,AGC,0*37 [ 2011-Sep-14 20:44:56.319021 ] {goby::acomms::modemdriver::out::2}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.421147 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,AGC,0*35 [ 2011-Sep-14 20:44:56.421613 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on automatic gain control [ 2011-Sep-14 20:44:56.523519 ] {goby::acomms::modemdriver::out::2}: D: $CCCFG,AGN,0*3A [ 2011-Sep-14 20:44:56.524008 ] {goby::acomms::modemdriver::out::2}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.626239 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,AGN,0*38 [ 2011-Sep-14 20:44:56.626709 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Analog Gain (50 is 6 dB, 250 is 30 dB) [ 2011-Sep-14 20:44:56.728623 ] {goby::acomms::modemdriver::out::2}: D: $CCCFG,SRC,2*32 [ 2011-Sep-14 20:44:56.729100 ] {goby::acomms::modemdriver::out::2}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:56.830135 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SRC,2*30 [ 2011-Sep-14 20:44:56.830262 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Default Source Address [ 2011-Sep-14 20:44:56.931048 ] {goby::acomms::modemdriver::out::2}: D: $CCCFG,REV,1*32 [ 2011-Sep-14 20:44:56.931191 ] {goby::acomms::modemdriver::out::2}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:57.032454 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,REV,1*30 [ 2011-Sep-14 20:44:57.032696 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CAREV message [ 2011-Sep-14 20:44:57.133659 ] {goby::acomms::modemdriver::out::2}: D: $CCCFG,RXP,1*29 [ 2011-Sep-14 20:44:57.133841 ] {goby::acomms::modemdriver::out::2}: ^ Set NVRAM configuration parameter, host to modem [ 2011-Sep-14 20:44:57.234707 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,RXP,1*2B [ 2011-Sep-14 20:44:57.234834 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXP message [ 2011-Sep-14 20:44:57.336745 ] {goby::acomms::modemdriver::out::2}: D: $CCCFQ,ALL*39 [ 2011-Sep-14 20:44:57.337245 ] {goby::acomms::modemdriver::out::2}: ^ Query configuration parameter, host to modem [ 2011-Sep-14 20:44:57.450532 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,AGC,0*35 [ 2011-Sep-14 20:44:57.451115 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on automatic gain control [ 2011-Sep-14 20:44:57.451381 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,AGN,0*38 [ 2011-Sep-14 20:44:57.451507 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Analog Gain (50 is 6 dB, 250 is 30 dB) [ 2011-Sep-14 20:44:57.451679 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,AGX,1*2F [ 2011-Sep-14 20:44:57.451813 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.451984 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,ASD,0*26 [ 2011-Sep-14 20:44:57.452109 ] {goby::acomms::modemdriver::in::2}: ^ 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.452295 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BBD,0*34 [ 2011-Sep-14 20:44:57.452421 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: PSK Baseband data dump to serial port [ 2011-Sep-14 20:44:57.452593 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BSP,1*30 [ 2011-Sep-14 20:44:57.452719 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Boot loader serial port [ 2011-Sep-14 20:44:57.452912 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BND,3*3B [ 2011-Sep-14 20:44:57.453038 ] {goby::acomms::modemdriver::in::2}: ^ 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.453223 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BR1,3*52 [ 2011-Sep-14 20:44:57.453348 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Baud rate for serial port 1 (3 = 19200) [ 2011-Sep-14 20:44:57.453520 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BR2,3*51 [ 2011-Sep-14 20:44:57.453646 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Baud rate for serial port 2 (3 = 19200) [ 2011-Sep-14 20:44:57.453827 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BRN,0*2E [ 2011-Sep-14 20:44:57.453953 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Run bootloader at next revert [ 2011-Sep-14 20:44:57.454126 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,BW0,4000*61 [ 2011-Sep-14 20:44:57.454265 ] {goby::acomms::modemdriver::in::2}: ^ 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.571234 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,CPR,1*30 [ 2011-Sep-14 20:44:57.571362 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.571536 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,CRL,50*18 [ 2011-Sep-14 20:44:57.571662 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle init reverb lockout (ms) 50 [ 2011-Sep-14 20:44:57.571850 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,CST,1*35 [ 2011-Sep-14 20:44:57.571975 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle statistics message 1 [ 2011-Sep-14 20:44:57.572141 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,CTO,10*19 [ 2011-Sep-14 20:44:57.572277 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Cycle init timeout (sec) 10 [ 2011-Sep-14 20:44:57.572445 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DBG,0*31 [ 2011-Sep-14 20:44:57.572568 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Enable low-level debug messages 0 [ 2011-Sep-14 20:44:57.572735 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DGM,0*3E [ 2011-Sep-14 20:44:57.572868 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Diagnostic messaging 0 [ 2011-Sep-14 20:44:57.573035 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DOP,0*2B [ 2011-Sep-14 20:44:57.573170 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CADOP message [ 2011-Sep-14 20:44:57.573346 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DQF,1*22 [ 2011-Sep-14 20:44:57.573468 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CADQF message [ 2011-Sep-14 20:44:57.573638 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DTH,108*21 [ 2011-Sep-14 20:44:57.573761 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Matched filter signal threshold, FSK [ 2011-Sep-14 20:44:57.573939 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DTO,2*2D [ 2011-Sep-14 20:44:57.574061 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Data request timeout (sec) [ 2011-Sep-14 20:44:57.574246 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,DTP,90*09 [ 2011-Sep-14 20:44:57.574369 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Matched filter signal threshold, PSK [ 2011-Sep-14 20:44:57.574539 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,ECD,50*07 [ 2011-Sep-14 20:44:57.574662 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Int Delay at end of cycle (ms) [ 2011-Sep-14 20:44:57.574830 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,EFF,20*07 [ 2011-Sep-14 20:44:57.574946 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Feedforward taps for the LMS equalizer [ 2011-Sep-14 20:44:57.691228 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,EFB,10*00 [ 2011-Sep-14 20:44:57.691353 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Feedback taps for the LMS equalizer [ 2011-Sep-14 20:44:57.691521 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,FC0,25120*41 [ 2011-Sep-14 20:44:57.691643 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Carrier at Band 0 PSK only [ 2011-Sep-14 20:44:57.691820 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,FML,200*35 [ 2011-Sep-14 20:44:57.691941 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: PSK FM probe length, symbols [ 2011-Sep-14 20:44:57.692109 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,FMD,1*3E [ 2011-Sep-14 20:44:57.692245 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: PSK FM probe direction,0 up, 1 down [ 2011-Sep-14 20:44:57.692413 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,GPS,0*34 [ 2011-Sep-14 20:44:57.692535 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: GPS parser on aux. serial port [ 2011-Sep-14 20:44:57.692709 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,HFC,0*3D [ 2011-Sep-14 20:44:57.692839 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Hardware flow control on main serial port [ 2011-Sep-14 20:44:57.693005 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,IRE,0*2E [ 2011-Sep-14 20:44:57.693126 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Print impulse response of FM sweep [ 2011-Sep-14 20:44:57.693305 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,MOD,0*36 [ 2011-Sep-14 20:44:57.693426 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: 0 sends FSK minipacket, 1 sends PSK minipacket [ 2011-Sep-14 20:44:57.693593 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,MFD,0*3F [ 2011-Sep-14 20:44:57.693716 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the MFD messages [ 2011-Sep-14 20:44:57.693892 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,MSE,0*2B [ 2011-Sep-14 20:44:57.694015 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Print symbol mean squared error (dB) from the LMS equalizer [ 2011-Sep-14 20:44:57.694202 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,MCM,1*32 [ 2011-Sep-14 20:44:57.694330 ] {goby::acomms::modemdriver::in::2}: ^ 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.694498 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,MPR,1*3E [ 2011-Sep-14 20:44:57.694621 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Enable power toggling on Multi-Channel Analog Board [ 2011-Sep-14 20:44:57.694796 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,MVM,1*27 [ 2011-Sep-14 20:44:57.694921 ] {goby::acomms::modemdriver::in::2}: ^ 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.811624 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,NDT,120*2D [ 2011-Sep-14 20:44:57.811746 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Detect threshold for nav detector [ 2011-Sep-14 20:44:57.811923 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,NPT,50*0F [ 2011-Sep-14 20:44:57.812044 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Power threshold for nav detector [ 2011-Sep-14 20:44:57.812227 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,NRL,25*17 [ 2011-Sep-14 20:44:57.812350 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Navigation reverb lockout (ms) [ 2011-Sep-14 20:44:57.812518 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,NRV,150*3E [ 2011-Sep-14 20:44:57.812641 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Number of CTOs before hard reboot [ 2011-Sep-14 20:44:57.812816 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,PAD,2*27 [ 2011-Sep-14 20:44:57.812940 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Power-amp delay (ms) [ 2011-Sep-14 20:44:57.813118 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,PCM,0*2E [ 2011-Sep-14 20:44:57.813252 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Passband channel mask [ 2011-Sep-14 20:44:57.813422 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,PRL,50*0B [ 2011-Sep-14 20:44:57.813544 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.813712 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,PTH,50*09 [ 2011-Sep-14 20:44:57.813844 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Matched filter detector power threshold [ 2011-Sep-14 20:44:57.814011 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,POW,-100*14 [ 2011-Sep-14 20:44:57.814134 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Detection power threshold (dB) PRL Int Packet reverb lockout (ms) [ 2011-Sep-14 20:44:57.814314 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,PTO,14*0E [ 2011-Sep-14 20:44:57.814436 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Packet timeout (sec) [ 2011-Sep-14 20:44:57.814603 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,REV,1*30 [ 2011-Sep-14 20:44:57.814726 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CAREV message [ 2011-Sep-14 20:44:57.814906 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,RSP,-157*0F [ 2011-Sep-14 20:44:57.815033 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: [ 2011-Sep-14 20:44:57.815204 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,RXA,0*3B [ 2011-Sep-14 20:44:57.815325 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXA message [ 2011-Sep-14 20:44:57.930271 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,RXD,1*3F [ 2011-Sep-14 20:44:57.930729 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXD message [ 2011-Sep-14 20:44:57.931331 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,RXP,1*2B [ 2011-Sep-14 20:44:57.931453 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CARXP message [ 2011-Sep-14 20:44:57.931628 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SCG,0*27 [ 2011-Sep-14 20:44:57.931750 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Set clock from GPS [ 2011-Sep-14 20:44:57.931927 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SGP,1*35 [ 2011-Sep-14 20:44:57.932048 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Show GPS messages on main serial port [ 2011-Sep-14 20:44:57.932287 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SHF,0*2D [ 2011-Sep-14 20:44:57.932410 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Whether or not to send the $CASHF message [ 2011-Sep-14 20:44:57.932580 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SNR,0*3F [ 2011-Sep-14 20:44:57.932702 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on SNR stats for PSK comms [ 2011-Sep-14 20:44:57.932879 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SNV,0*3B [ 2011-Sep-14 20:44:57.933001 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Synchronous transmission of packets [ 2011-Sep-14 20:44:57.933181 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,SRC,2*30 [ 2011-Sep-14 20:44:57.933302 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Default Source Address [ 2011-Sep-14 20:44:57.933467 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,TAT,50*04 [ 2011-Sep-14 20:44:57.933589 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Navigation turn-around-time (msec) [ 2011-Sep-14 20:44:57.933756 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,TOA,0*2A [ 2011-Sep-14 20:44:57.933888 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Display time of arrival of a packet (sec) [ 2011-Sep-14 20:44:57.934055 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,TXD,600*3E [ 2011-Sep-14 20:44:57.934178 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Delay before transmit (ms) [ 2011-Sep-14 20:44:57.934344 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,TXP,1*2D [ 2011-Sep-14 20:44:57.934467 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on start of transmit message [ 2011-Sep-14 20:44:57.934634 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,TXF,1*3B [ 2011-Sep-14 20:44:57.934756 ] {goby::acomms::modemdriver::in::2}: ^ Echo of NVRAM configuration parameter, modem to host: Turn on end of transmit message [ 2011-Sep-14 20:44:58.037825 ] {goby::acomms::modemdriver::in::2}: D: $CACFG,XST,1*2E [ 2011-Sep-14 20:44:58.038285 ] {goby::acomms::modemdriver::in::2}: ^ 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.348030 ] {goby::acomms::modemdriver::in::2}: D: $CARXP,0*44 [ 2011-Sep-14 20:44:59.348536 ] {goby::acomms::modemdriver::in::2}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:00.162545 ] {goby::acomms::modemdriver::in::2}: D: $CADQF,249,2*5C [ 2011-Sep-14 20:45:00.162668 ] {goby::acomms::modemdriver::in::2}: ^ Data quality factor information, modem to host [ 2011-Sep-14 20:45:00.162832 ] {goby::acomms::modemdriver::in::2}: D: $CAMPA,1,2*5D [ 2011-Sep-14 20:45:00.162956 ] {goby::acomms::modemdriver::in::2}: ^ A Ping has been received, modem to host [ 2011-Sep-14 20:45:00.163219 ] {goby::acomms::modemdriver::in::2}: D: $CACST,0,204459.0000,1,2033,21,0380,0169,00,01,01,02,02,0,001,002,0,2,1,0,-01,-1,-1,-1,00,249,0.0,-1,25120,4000*72 [ 2011-Sep-14 20:45:00.163343 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:00.163740 ] {}: D: time is: 2011-Sep-14 20:44:59 [ 2011-Sep-14 20:45:00.163963 ] {driver2}: Received: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:00.164091 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:00.164211 ] {driver2}: time: 1316033099000000 [ 2011-Sep-14 20:45:00.164331 ] {driver2}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:00.164452 ] {driver2}: type: MICROMODEM_TWO_WAY_PING [ 2011-Sep-14 20:45:00.164573 ] {driver2}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:00.164693 ] {driver2}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:00.164814 ] {driver2}: time: "204459.0000" [ 2011-Sep-14 20:45:00.164935 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:00.165056 ] {driver2}: mfd_peak: 2033 [ 2011-Sep-14 20:45:00.165176 ] {driver2}: mfd_power: 21 [ 2011-Sep-14 20:45:00.165297 ] {driver2}: mfd_ratio: 380 [ 2011-Sep-14 20:45:00.165417 ] {driver2}: spl: 169 [ 2011-Sep-14 20:45:00.165538 ] {driver2}: shf_agn: 0 [ 2011-Sep-14 20:45:00.165658 ] {driver2}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:00.165780 ] {driver2}: shf_ainshift: 1 [ 2011-Sep-14 20:45:00.165905 ] {driver2}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:00.166019 ] {driver2}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:00.166134 ] {driver2}: rate: 0 [ 2011-Sep-14 20:45:00.166248 ] {driver2}: source: 1 [ 2011-Sep-14 20:45:00.166363 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:00.166477 ] {driver2}: psk_error_code: 0 [ 2011-Sep-14 20:45:00.166591 ] {driver2}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:00.166706 ] {driver2}: number_frames: 1 [ 2011-Sep-14 20:45:00.166821 ] {driver2}: number_bad_frames: 0 [ 2011-Sep-14 20:45:00.166935 ] {driver2}: snr_rss: -1 [ 2011-Sep-14 20:45:00.167050 ] {driver2}: snr_in: -1 [ 2011-Sep-14 20:45:00.167164 ] {driver2}: snr_out: -1 [ 2011-Sep-14 20:45:00.167278 ] {driver2}: snr_symbols: -1 [ 2011-Sep-14 20:45:00.167392 ] {driver2}: mse_equalizer: 0 [ 2011-Sep-14 20:45:00.167506 ] {driver2}: data_quality_factor: 249 [ 2011-Sep-14 20:45:00.167621 ] {driver2}: doppler: 0 [ 2011-Sep-14 20:45:00.167735 ] {driver2}: stddev_noise: -1 [ 2011-Sep-14 20:45:00.167850 ] {driver2}: carrier_freq: 25120 [ 2011-Sep-14 20:45:00.167964 ] {driver2}: bandwidth: 4000 [ 2011-Sep-14 20:45:00.168078 ] {driver2}: version: 0 [ 2011-Sep-14 20:45:00.168192 ] {driver2}: } [ 2011-Sep-14 20:45:00.168305 ] {driver2}: [ 2011-Sep-14 20:45:00.271296 ] {goby::acomms::modemdriver::in::2}: D: $CATXP,0*42 [ 2011-Sep-14 20:45:00.271759 ] {goby::acomms::modemdriver::in::2}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:02.179497 ] {goby::acomms::modemdriver::in::2}: D: $CATXF,0*54 [ 2011-Sep-14 20:45:02.179960 ] {goby::acomms::modemdriver::in::2}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:02.180644 ] {goby::acomms::modemdriver::in::2}: D: $CAXST,20110914,204500.0000,1,0,040,4000,25120,2,002,001,1,1,1,2,0000*65 [ 2011-Sep-14 20:45:02.181290 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:02.181946 ] {driver2}: Completed transmit: [[ModemTransmission]] [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:02.182470 ] {driver2}: date: "20110914" [ 2011-Sep-14 20:45:02.182926 ] {driver2}: time: "204500.0000" [ 2011-Sep-14 20:45:02.183380 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:02.183835 ] {driver2}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:02.184289 ] {driver2}: probe_length: 40 [ 2011-Sep-14 20:45:02.184744 ] {driver2}: bandwidth: 4000 [ 2011-Sep-14 20:45:02.185198 ] {driver2}: carrier_freq: 25120 [ 2011-Sep-14 20:45:02.185652 ] {driver2}: rate: 2 [ 2011-Sep-14 20:45:02.186107 ] {driver2}: source: 2 [ 2011-Sep-14 20:45:02.186560 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:02.187028 ] {driver2}: ack_requested: true [ 2011-Sep-14 20:45:02.187482 ] {driver2}: number_frames_expected: 1 [ 2011-Sep-14 20:45:02.187938 ] {driver2}: number_frames_sent: 1 [ 2011-Sep-14 20:45:02.188392 ] {driver2}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:02.188846 ] {driver2}: number_bytes: 0 [ 2011-Sep-14 20:45:02.189302 ] {driver2}: } [ 2011-Sep-14 20:45:02.189759 ] {driver2}: [ 2011-Sep-14 20:45:02.292116 ] {}: D: time is: 2011-Sep-14 20:45:01 [ 2011-Sep-14 20:45:02.304941 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204502,AUV,0.94.0.00*0F [ 2011-Sep-14 20:45:02.305065 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:02.305250 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204502,COPROC,0.10.0.46*41 [ 2011-Sep-14 20:45:02.305372 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 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:05.023673 ] {test}: Test 1 passed. [ 2011-Sep-14 20:45:06.024608 ] {test}: Narrowband LBL test [ 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.645144 ] {driver2}: Can modify: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:08.645603 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:08.646055 ] {driver2}: type: MICROMODEM_MINI_DATA [ 2011-Sep-14 20:45:08.646503 ] {driver2}: [ 2011-Sep-14 20:45:08.647039 ] {driver2}: Data request: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:08.647495 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:08.647950 ] {driver2}: type: MICROMODEM_MINI_DATA [ 2011-Sep-14 20:45:08.648404 ] {driver2}: max_num_frames: 1 [ 2011-Sep-14 20:45:08.648859 ] {driver2}: max_frame_bytes: 2 [ 2011-Sep-14 20:45:08.649309 ] {driver2}: [ 2011-Sep-14 20:45:08.649873 ] {driver2}: Post data request: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:08.650328 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:08.650782 ] {driver2}: type: MICROMODEM_MINI_DATA [ 2011-Sep-14 20:45:08.651239 ] {driver2}: max_num_frames: 1 [ 2011-Sep-14 20:45:08.651692 ] {driver2}: max_frame_bytes: 2 [ 2011-Sep-14 20:45:08.652146 ] {driver2}: frame: "\001#" [ 2011-Sep-14 20:45:08.652710 ] {driver2}: [ 2011-Sep-14 20:45:08.653526 ] {goby::acomms::modemdriver::out::2}: D: $CCMUC,2,1,0123*74 [ 2011-Sep-14 20:45:08.653990 ] {goby::acomms::modemdriver::out::2}: ^ User Mini-Packet command, host to modem [ 2011-Sep-14 20:45:08.754970 ] {goby::acomms::modemdriver::in::2}: D: $CAMUC,2,1,0123*76 [ 2011-Sep-14 20:45:08.755460 ] {goby::acomms::modemdriver::in::2}: ^ Echo of user Mini-Packet, modem to host [ 2011-Sep-14 20:45:08.756080 ] {goby::acomms::modemdriver::in::2}: D: $CATXP,0*42 [ 2011-Sep-14 20:45:08.756541 ] {goby::acomms::modemdriver::in::2}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:09.662282 ] {goby::acomms::modemdriver::in::2}: D: $CATXF,0*54 [ 2011-Sep-14 20:45:09.662755 ] {goby::acomms::modemdriver::in::2}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:09.663442 ] {goby::acomms::modemdriver::in::2}: D: $CAXST,20110914,204508.0000,1,0,040,4000,25120,2,002,001,1,1,1,2,0000*6D [ 2011-Sep-14 20:45:09.663905 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:09.664565 ] {driver2}: Completed transmit: [[ModemTransmission]] src: 2 [ 2011-Sep-14 20:45:09.665024 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:09.665480 ] {driver2}: type: MICROMODEM_MINI_DATA [ 2011-Sep-14 20:45:09.665935 ] {driver2}: max_num_frames: 1 [ 2011-Sep-14 20:45:09.666390 ] {driver2}: max_frame_bytes: 2 [ 2011-Sep-14 20:45:09.666845 ] {driver2}: frame: "\001#" [ 2011-Sep-14 20:45:09.667302 ] {driver2}: [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:09.667758 ] {driver2}: date: "20110914" [ 2011-Sep-14 20:45:09.668213 ] {driver2}: time: "204508.0000" [ 2011-Sep-14 20:45:09.668669 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:09.669125 ] {driver2}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:09.669584 ] {driver2}: probe_length: 40 [ 2011-Sep-14 20:45:09.670046 ] {driver2}: bandwidth: 4000 [ 2011-Sep-14 20:45:09.670503 ] {driver2}: carrier_freq: 25120 [ 2011-Sep-14 20:45:09.670960 ] {driver2}: rate: 2 [ 2011-Sep-14 20:45:09.671416 ] {driver2}: source: 2 [ 2011-Sep-14 20:45:09.671871 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:09.672387 ] {driver2}: ack_requested: true [ 2011-Sep-14 20:45:09.672558 ] {driver2}: number_frames_expected: 1 [ 2011-Sep-14 20:45:09.672679 ] {driver2}: number_frames_sent: 1 [ 2011-Sep-14 20:45:09.672800 ] {driver2}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:09.672919 ] {driver2}: number_bytes: 0 [ 2011-Sep-14 20:45:09.673039 ] {driver2}: } [ 2011-Sep-14 20:45:09.673158 ] {driver2}: [ 2011-Sep-14 20:45:09.775307 ] {}: D: time is: 2011-Sep-14 20:45:08 [ 2011-Sep-14 20:45:09.792850 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204509,AUV,0.94.0.00*04 [ 2011-Sep-14 20:45:09.792973 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:09.793152 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204509,COPROC,0.10.0.46*4A [ 2011-Sep-14 20:45:09.793276 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 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:11.110482 ] {goby::acomms::modemdriver::in::2}: D: $CARXP,0*44 [ 2011-Sep-14 20:45:11.110968 ] {goby::acomms::modemdriver::in::2}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:11.917896 ] {goby::acomms::modemdriver::in::2}: D: $CADQF,249,2*5C [ 2011-Sep-14 20:45:11.918358 ] {goby::acomms::modemdriver::in::2}: ^ Data quality factor information, modem to host [ 2011-Sep-14 20:45:11.918988 ] {goby::acomms::modemdriver::in::2}: D: $CACYC,0,1,2,0,1,1*58 [ 2011-Sep-14 20:45:11.919449 ] {goby::acomms::modemdriver::in::2}: ^ Echo of Network Cycle Initialization command [ 2011-Sep-14 20:45:12.020634 ] {goby::acomms::modemdriver::in::2}: D: $CACST,0,204510.0000,1,2165,21,0420,0170,00,01,01,02,02,0,001,002,0,2,1,0,-01,-1,-1,-1,00,249,-0.0,-1,25120,4000*54 [ 2011-Sep-14 20:45:12.021150 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:12.021873 ] {}: D: time is: 2011-Sep-14 20:45:10 [ 2011-Sep-14 20:45:12.725615 ] {goby::acomms::modemdriver::in::2}: D: $CARXP,0*44 [ 2011-Sep-14 20:45:12.726098 ] {goby::acomms::modemdriver::in::2}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:16.853915 ] {goby::acomms::modemdriver::in::2}: D: $CADQF,248,1*5E [ 2011-Sep-14 20:45:16.854036 ] {goby::acomms::modemdriver::in::2}: ^ Data quality factor information, modem to host [ 2011-Sep-14 20:45:16.854207 ] {goby::acomms::modemdriver::in::2}: D: $CARXD,1,2,1,1,3535353535353535353535353535353535353535353535353535353535353535*63 [ 2011-Sep-14 20:45:16.854328 ] {goby::acomms::modemdriver::in::2}: ^ Received binary message, modem to host [ 2011-Sep-14 20:45:16.955243 ] {goby::acomms::modemdriver::in::2}: D: $CACST,0,204512.0000,1,2300,21,0443,0171,00,01,01,02,02,0,001,002,0,1,1,0,-01,-1,-1,-1,00,248,-0.0,-1,25120,4000*51 [ 2011-Sep-14 20:45:16.955731 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:16.956444 ] {}: D: time is: 2011-Sep-14 20:45:12 [ 2011-Sep-14 20:45:16.957150 ] {driver2}: Received: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:16.957608 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:16.958061 ] {driver2}: time: 1316033112000000 [ 2011-Sep-14 20:45:16.958514 ] {driver2}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:16.958965 ] {driver2}: type: DATA [ 2011-Sep-14 20:45:16.959415 ] {driver2}: ack_requested: true [ 2011-Sep-14 20:45:16.959871 ] {driver2}: frame: "55555555555555555555555555555555" [ 2011-Sep-14 20:45:16.960324 ] {driver2}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:16.960777 ] {driver2}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:16.961235 ] {driver2}: time: "204510.0000" [ 2011-Sep-14 20:45:16.961688 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:16.962140 ] {driver2}: mfd_peak: 2165 [ 2011-Sep-14 20:45:16.962660 ] {driver2}: mfd_power: 21 [ 2011-Sep-14 20:45:16.963112 ] {driver2}: mfd_ratio: 420 [ 2011-Sep-14 20:45:16.963564 ] {driver2}: spl: 170 [ 2011-Sep-14 20:45:16.964017 ] {driver2}: shf_agn: 0 [ 2011-Sep-14 20:45:16.964470 ] {driver2}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:16.964927 ] {driver2}: shf_ainshift: 1 [ 2011-Sep-14 20:45:16.965386 ] {driver2}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:16.965838 ] {driver2}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:16.966290 ] {driver2}: rate: 0 [ 2011-Sep-14 20:45:16.966742 ] {driver2}: source: 1 [ 2011-Sep-14 20:45:16.967193 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:16.967645 ] {driver2}: psk_error_code: 0 [ 2011-Sep-14 20:45:16.968099 ] {driver2}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:16.968551 ] {driver2}: number_frames: 1 [ 2011-Sep-14 20:45:16.969004 ] {driver2}: number_bad_frames: 0 [ 2011-Sep-14 20:45:16.969457 ] {driver2}: snr_rss: -1 [ 2011-Sep-14 20:45:16.969909 ] {driver2}: snr_in: -1 [ 2011-Sep-14 20:45:16.970382 ] {driver2}: snr_out: -1 [ 2011-Sep-14 20:45:16.970836 ] {driver2}: snr_symbols: -1 [ 2011-Sep-14 20:45:16.971288 ] {driver2}: mse_equalizer: 0 [ 2011-Sep-14 20:45:16.971745 ] {driver2}: data_quality_factor: 249 [ 2011-Sep-14 20:45:16.972249 ] {driver2}: doppler: -0 [ 2011-Sep-14 20:45:16.972520 ] {driver2}: stddev_noise: -1 [ 2011-Sep-14 20:45:16.972641 ] {driver2}: carrier_freq: 25120 [ 2011-Sep-14 20:45:16.972761 ] {driver2}: bandwidth: 4000 [ 2011-Sep-14 20:45:16.972881 ] {driver2}: version: 0 [ 2011-Sep-14 20:45:16.973001 ] {driver2}: } [ 2011-Sep-14 20:45:16.973122 ] {driver2}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:16.973242 ] {driver2}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:16.973362 ] {driver2}: time: "204512.0000" [ 2011-Sep-14 20:45:16.973483 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:16.973603 ] {driver2}: mfd_peak: 2300 [ 2011-Sep-14 20:45:16.973724 ] {driver2}: mfd_power: 21 [ 2011-Sep-14 20:45:16.973844 ] {driver2}: mfd_ratio: 443 [ 2011-Sep-14 20:45:16.973964 ] {driver2}: spl: 171 [ 2011-Sep-14 20:45:16.974100 ] {driver2}: shf_agn: 0 [ 2011-Sep-14 20:45:16.974220 ] {driver2}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:16.974339 ] {driver2}: shf_ainshift: 1 [ 2011-Sep-14 20:45:16.974460 ] {driver2}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:16.974580 ] {driver2}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:16.974701 ] {driver2}: rate: 0 [ 2011-Sep-14 20:45:16.974821 ] {driver2}: source: 1 [ 2011-Sep-14 20:45:16.974941 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:16.975061 ] {driver2}: psk_error_code: 0 [ 2011-Sep-14 20:45:16.975183 ] {driver2}: packet_type: FSK [ 2011-Sep-14 20:45:16.975303 ] {driver2}: number_frames: 1 [ 2011-Sep-14 20:45:16.975424 ] {driver2}: number_bad_frames: 0 [ 2011-Sep-14 20:45:16.975544 ] {driver2}: snr_rss: -1 [ 2011-Sep-14 20:45:16.975664 ] {driver2}: snr_in: -1 [ 2011-Sep-14 20:45:16.975785 ] {driver2}: snr_out: -1 [ 2011-Sep-14 20:45:16.975906 ] {driver2}: snr_symbols: -1 [ 2011-Sep-14 20:45:16.976029 ] {driver2}: mse_equalizer: 0 [ 2011-Sep-14 20:45:16.976143 ] {driver2}: data_quality_factor: 248 [ 2011-Sep-14 20:45:16.976257 ] {driver2}: doppler: -0 [ 2011-Sep-14 20:45:16.976372 ] {driver2}: stddev_noise: -1 [ 2011-Sep-14 20:45:16.976486 ] {driver2}: carrier_freq: 25120 [ 2011-Sep-14 20:45:16.976600 ] {driver2}: bandwidth: 4000 [ 2011-Sep-14 20:45:16.976714 ] {driver2}: version: 0 [ 2011-Sep-14 20:45:16.976828 ] {driver2}: } [ 2011-Sep-14 20:45:16.976941 ] {driver2}: [ 2011-Sep-14 20:45:16.977113 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204516,AUV,0.94.0.00*0A [ 2011-Sep-14 20:45:16.977230 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:16.977400 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204516,COPROC,0.10.0.46*44 [ 2011-Sep-14 20:45:16.977517 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:17.078245 ] {goby::acomms::modemdriver::in::2}: D: $CATXP,0*42 [ 2011-Sep-14 20:45:17.078746 ] {goby::acomms::modemdriver::in::2}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:17.984709 ] {goby::acomms::modemdriver::in::2}: D: $CATXF,0*54 [ 2011-Sep-14 20:45:17.985171 ] {goby::acomms::modemdriver::in::2}: ^ End of packet transmission, modem to host [ 2011-Sep-14 20:45:17.985868 ] {goby::acomms::modemdriver::in::2}: D: $CAXST,20110914,204516.0000,1,0,040,4000,25120,0,002,001,1,1,1,2,0000*60 [ 2011-Sep-14 20:45:17.986328 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:17.986969 ] {driver2}: Completed transmit: [[ModemTransmission]] [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:17.987427 ] {driver2}: date: "20110914" [ 2011-Sep-14 20:45:17.987882 ] {driver2}: time: "204516.0000" [ 2011-Sep-14 20:45:17.988336 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:17.988791 ] {driver2}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:17.989248 ] {driver2}: probe_length: 40 [ 2011-Sep-14 20:45:17.989704 ] {driver2}: bandwidth: 4000 [ 2011-Sep-14 20:45:17.990162 ] {driver2}: carrier_freq: 25120 [ 2011-Sep-14 20:45:17.990622 ] {driver2}: rate: 0 [ 2011-Sep-14 20:45:17.991081 ] {driver2}: source: 2 [ 2011-Sep-14 20:45:17.991543 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:17.992002 ] {driver2}: ack_requested: true [ 2011-Sep-14 20:45:17.992663 ] {driver2}: number_frames_expected: 1 [ 2011-Sep-14 20:45:17.993119 ] {driver2}: number_frames_sent: 1 [ 2011-Sep-14 20:45:17.993575 ] {driver2}: packet_type: FSK_MINI [ 2011-Sep-14 20:45:17.994034 ] {driver2}: number_bytes: 0 [ 2011-Sep-14 20:45:17.994492 ] {driver2}: } [ 2011-Sep-14 20:45:17.994948 ] {driver2}: [ 2011-Sep-14 20:45:18.097308 ] {}: D: time is: 2011-Sep-14 20:45:17 [ 2011-Sep-14 20:45:18.113478 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204517,AUV,0.94.0.00*0B [ 2011-Sep-14 20:45:18.113599 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:18.113780 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204517,COPROC,0.10.0.46*45 [ 2011-Sep-14 20:45:18.113902 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 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:20.245161 ] {goby::acomms::modemdriver::in::2}: D: $CARXP,1*45 [ 2011-Sep-14 20:45:20.245677 ] {goby::acomms::modemdriver::in::2}: ^ Incoming packet detected, modem to host [ 2011-Sep-14 20:45:23.969184 ] {goby::acomms::modemdriver::in::2}: D: $CACYC,1,1,2,2,1,3*59 [ 2011-Sep-14 20:45:23.969677 ] {goby::acomms::modemdriver::in::2}: ^ Echo of Network Cycle Initialization command [ 2011-Sep-14 20:45:24.070724 ] {goby::acomms::modemdriver::in::2}: D: $CARXD,1,2,1,1,31313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131313131*63 [ 2011-Sep-14 20:45:24.071235 ] {goby::acomms::modemdriver::in::2}: ^ Received binary message, modem to host [ 2011-Sep-14 20:45:24.072028 ] {goby::acomms::modemdriver::in::2}: D: $CARXD,1,2,1,2,32323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232323232*60 [ 2011-Sep-14 20:45:24.072561 ] {goby::acomms::modemdriver::in::2}: ^ Received binary message, modem to host [ 2011-Sep-14 20:45:24.073223 ] {goby::acomms::modemdriver::in::2}: D: $CARXD,1,2,1,3,*61 [ 2011-Sep-14 20:45:24.073782 ] {goby::acomms::modemdriver::in::2}: ^ Received binary message, modem to host [ 2011-Sep-14 20:45:24.174814 ] {goby::acomms::modemdriver::in::2}: D: $CACST,0,204520.0000,1,2138,21,0395,0170,00,01,01,02,02,2,001,002,0,3,3,0,163,72,32,40,-29,000,0.0,-12,25000,5000*67 [ 2011-Sep-14 20:45:24.175323 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle receive statistics [ 2011-Sep-14 20:45:24.176079 ] {}: D: time is: 2011-Sep-14 20:45:20 [ 2011-Sep-14 20:45:24.176759 ] {driver2}: Received: [[ModemTransmission]] src: 1 [ 2011-Sep-14 20:45:24.177221 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:24.177678 ] {driver2}: time: 1316033120000000 [ 2011-Sep-14 20:45:24.178134 ] {driver2}: time_source: MODEM_TIME [ 2011-Sep-14 20:45:24.178590 ] {driver2}: type: DATA [ 2011-Sep-14 20:45:24.179045 ] {driver2}: ack_requested: true [ 2011-Sep-14 20:45:24.179503 ] {driver2}: frame: "1111111111111111111111111111111111111111111111111111111111111111" [ 2011-Sep-14 20:45:24.179959 ] {driver2}: frame: "2222222222222222222222222222222222222222222222222222222222222222" [ 2011-Sep-14 20:45:24.180416 ] {driver2}: [micromodem.protobuf.receive_stat] { [ 2011-Sep-14 20:45:24.180871 ] {driver2}: mode: RECEIVE_GOOD [ 2011-Sep-14 20:45:24.181334 ] {driver2}: time: "204520.0000" [ 2011-Sep-14 20:45:24.181791 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:24.182330 ] {driver2}: mfd_peak: 2138 [ 2011-Sep-14 20:45:24.182829 ] {driver2}: mfd_power: 21 [ 2011-Sep-14 20:45:24.183284 ] {driver2}: mfd_ratio: 395 [ 2011-Sep-14 20:45:24.183739 ] {driver2}: spl: 170 [ 2011-Sep-14 20:45:24.184194 ] {driver2}: shf_agn: 0 [ 2011-Sep-14 20:45:24.184661 ] {driver2}: shf_ainpshift: 1 [ 2011-Sep-14 20:45:24.185116 ] {driver2}: shf_ainshift: 1 [ 2011-Sep-14 20:45:24.185571 ] {driver2}: shf_mfdshift: 2 [ 2011-Sep-14 20:45:24.186025 ] {driver2}: shf_p2bshift: 2 [ 2011-Sep-14 20:45:24.186479 ] {driver2}: rate: 2 [ 2011-Sep-14 20:45:24.186934 ] {driver2}: source: 1 [ 2011-Sep-14 20:45:24.187388 ] {driver2}: dest: 2 [ 2011-Sep-14 20:45:24.187842 ] {driver2}: psk_error_code: 0 [ 2011-Sep-14 20:45:24.188297 ] {driver2}: packet_type: PSK [ 2011-Sep-14 20:45:24.188751 ] {driver2}: number_frames: 3 [ 2011-Sep-14 20:45:24.189207 ] {driver2}: number_bad_frames: 0 [ 2011-Sep-14 20:45:24.189662 ] {driver2}: snr_rss: 163 [ 2011-Sep-14 20:45:24.190117 ] {driver2}: snr_in: 72 [ 2011-Sep-14 20:45:24.190573 ] {driver2}: snr_out: 32 [ 2011-Sep-14 20:45:24.191029 ] {driver2}: snr_symbols: 40 [ 2011-Sep-14 20:45:24.191489 ] {driver2}: mse_equalizer: -29 [ 2011-Sep-14 20:45:24.191947 ] {driver2}: data_quality_factor: 0 [ 2011-Sep-14 20:45:24.192494 ] {driver2}: doppler: 0 [ 2011-Sep-14 20:45:24.192617 ] {driver2}: stddev_noise: -12 [ 2011-Sep-14 20:45:24.192732 ] {driver2}: carrier_freq: 25000 [ 2011-Sep-14 20:45:24.192847 ] {driver2}: bandwidth: 5000 [ 2011-Sep-14 20:45:24.192962 ] {driver2}: version: 0 [ 2011-Sep-14 20:45:24.193077 ] {driver2}: } [ 2011-Sep-14 20:45:24.193191 ] {driver2}: [ 2011-Sep-14 20:45:24.193375 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204524,AUV,0.94.0.00*0B [ 2011-Sep-14 20:45:24.193492 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:24.193677 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204524,COPROC,0.10.0.46*45 [ 2011-Sep-14 20:45:24.193810 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:24.394752 ] {goby::acomms::modemdriver::in::2}: D: $CATXP,0*42 [ 2011-Sep-14 20:45:24.395257 ] {goby::acomms::modemdriver::in::2}: ^ Start of packet transmission, modem to host [ 2011-Sep-14 20:45:25.198618 ] {goby::acomms::modemdriver::in::2}: D: $CATXF,0*54 [ 2011-Sep-14 20:45:25.199123 ] {goby::acomms::modemdriver::in::2}: ^ End of packet transmission, modem to host [ 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.304038 ] {goby::acomms::modemdriver::in::2}: D: $CAXST,20110914,204524.0000,1,0,200,5000,25000,1,002,001,1,1,1,4,0000*62 [ 2011-Sep-14 20:45:25.304496 ] {goby::acomms::modemdriver::in::2}: ^ Communication cycle transmit statistics [ 2011-Sep-14 20:45:25.305133 ] {driver2}: Completed transmit: [[ModemTransmission]] [micromodem.protobuf.transmit_stat] { [ 2011-Sep-14 20:45:25.305587 ] {driver2}: date: "20110914" [ 2011-Sep-14 20:45:25.306038 ] {driver2}: time: "204524.0000" [ 2011-Sep-14 20:45:25.306489 ] {driver2}: clock_mode: NO_SYNC_TO_PPS_AND_CCCLK_GOOD [ 2011-Sep-14 20:45:25.306940 ] {driver2}: mode: TRANSMIT_SUCCESSFUL [ 2011-Sep-14 20:45:25.307390 ] {driver2}: probe_length: 200 [ 2011-Sep-14 20:45:25.307840 ] {driver2}: bandwidth: 5000 [ 2011-Sep-14 20:45:25.308291 ] {driver2}: carrier_freq: 25000 [ 2011-Sep-14 20:45:25.308740 ] {driver2}: rate: 1 [ 2011-Sep-14 20:45:25.309189 ] {driver2}: source: 2 [ 2011-Sep-14 20:45:25.309644 ] {driver2}: dest: 1 [ 2011-Sep-14 20:45:25.310101 ] {driver2}: ack_requested: true [ 2011-Sep-14 20:45:25.310616 ] {driver2}: number_frames_expected: 1 [ 2011-Sep-14 20:45:25.311068 ] {driver2}: number_frames_sent: 1 [ 2011-Sep-14 20:45:25.311526 ] {driver2}: packet_type: PSK_MINI [ 2011-Sep-14 20:45:25.311979 ] {driver2}: number_bytes: 0 [ 2011-Sep-14 20:45:25.312463 ] {driver2}: } [ 2011-Sep-14 20:45:25.312581 ] {driver2}: [ 2011-Sep-14 20:45:25.312755 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204525,AUV,0.94.0.00*0A [ 2011-Sep-14 20:45:25.312877 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:25.414960 ] {}: D: time is: 2011-Sep-14 20:45:24 [ 2011-Sep-14 20:45:25.432904 ] {goby::acomms::modemdriver::in::2}: D: $CAREV,204525,COPROC,0.10.0.46*44 [ 2011-Sep-14 20:45:25.433027 ] {goby::acomms::modemdriver::in::2}: ^ Software revision message, modem to host [ 2011-Sep-14 20:45:25.533586 ] {test}: Test 5 passed. [ 2011-Sep-14 20:45:26.534525 ] {test}: all tests passed