Attachment 297 Details for Bug 933 – Log on failure.

Log on failure.

mso-log-failure.txt (text/plain), 13.08 KB, created by Martin Ling on 2017-05-09 19:55:51 CEST

(hide)

Description:

Filename:

MIME Type:

Creator: Martin Ling

Created: 2017-05-09 19:55:51 CEST

Size: 13.08 KB

patch

obsolete

>sr: [00:00.000001] log: libsigrok loglevel set to 5.
>sr: [00:00.000198] backend: libsigrok 0.5.0-git-bf8a02b/3:0:0 (rt: 0.5.0-git-bf8a02b/3:0:0).
>sr: [00:00.000253] backend: Libs: glib 2.42.1 (rt: 2.42.1/4201:1), libzip 0.11.2, libserialport 0.1.1/0:0:0 (rt: 0.1.1/0:0:0), libusb-1.0 1.0.19.10903, libftdi 0.20.
>sr: [00:00.000278] backend: Host: x86_64-unknown-linux-gnu, little-endian.
>sr: [00:00.000299] backend: SCPI backends: TCP, RPC, serial, USBTMC.
>sr: [00:00.000359] backend: Sanity-checking all drivers.
>sr: [00:00.000383] backend: Sanity-checking all input modules.
>sr: [00:00.000401] backend: Sanity-checking all output modules.
>sr: [00:00.000417] backend: Sanity-checking all transform modules.
>srd: libsigrokdecode loglevel set to 5.
>sr: [00:00.003795] hwdriver: sr_config_list(): key 2147418112 (NULL) sdi (nil) cg NULL -> [uint32 20000, 20001]
>sr: [00:00.003845] scpi: Opening VXI device vxi/10.38.38.133.
>sr: [00:00.287081] scpi_vxi: Successfully sent SCPI command: '*IDN?'.
>sr: [00:00.444677] scpi: Got response: 'RIGOL TECHNOLOGIES,MSO1104Z,DS1ZCXXXXXXXXX,00.04.02.SP4', length 55.
>sr: [00:00.444759] scpi: Got IDN string: 'RIGOL TECHNOLOGIES,MSO1104Z,DS1ZCXXXXXXXXX,00.04.02.SP4'
>sr: [00:00.455583] hwdriver: Scan of 'rigol-ds' found 1 devices.
>sr: [00:00.485539] scpi_vxi: Successfully sent SCPI command: ':CHAN1:DISP?'.
>sr: [00:00.649685] scpi: Got response: '1', length 1.
>sr: [00:00.653789] scpi_vxi: Successfully sent SCPI command: ':CHAN2:DISP?'.
>sr: [00:00.683961] scpi: Got response: '1', length 1.
>sr: [00:00.686838] scpi_vxi: Successfully sent SCPI command: ':CHAN3:DISP?'.
>sr: [00:00.688998] scpi: Got response: '0', length 1.
>sr: [00:00.691410] scpi_vxi: Successfully sent SCPI command: ':CHAN4:DISP?'.
>sr: [00:00.696371] scpi: Got response: '0', length 1.
>sr: [00:00.696450] rigol-ds: Current analog channel state:
>sr: [00:00.696486] rigol-ds: CH1 on
>sr: [00:00.696502] rigol-ds: CH2 on
>sr: [00:00.696517] rigol-ds: CH3 off
>sr: [00:00.696532] rigol-ds: CH4 off
>sr: [00:00.859017] scpi_vxi: Successfully sent SCPI command: ':LA:STAT?'.
>sr: [00:00.861830] scpi: Got response: '0', length 1.
>sr: [00:00.861880] rigol-ds: Logic analyzer disabled, current digital channel state:
>sr: [00:00.868865] scpi_vxi: Successfully sent SCPI command: ':LA:DIG0:DISP?'.
>sr: [00:00.879319] scpi: Got response: '0', length 1.
>sr: [00:00.879395] rigol-ds: D0: off
>sr: [00:00.882029] scpi_vxi: Successfully sent SCPI command: ':LA:DIG1:DISP?'.
>sr: [00:00.884231] scpi: Got response: '0', length 1.
>sr: [00:00.884299] rigol-ds: D1: off
>sr: [00:00.888727] scpi_vxi: Successfully sent SCPI command: ':LA:DIG2:DISP?'.
>sr: [00:00.891113] scpi: Got response: '0', length 1.
>sr: [00:00.891187] rigol-ds: D2: off
>sr: [00:00.893403] scpi_vxi: Successfully sent SCPI command: ':LA:DIG3:DISP?'.
>sr: [00:01.047688] scpi: Got response: '0', length 1.
>sr: [00:01.047773] rigol-ds: D3: off
>sr: [00:01.051022] scpi_vxi: Successfully sent SCPI command: ':LA:DIG4:DISP?'.
>sr: [00:01.058760] scpi: Got response: '0', length 1.
>sr: [00:01.058844] rigol-ds: D4: off
>sr: [00:01.086442] scpi_vxi: Successfully sent SCPI command: ':LA:DIG5:DISP?'.
>sr: [00:01.088754] scpi: Got response: '0', length 1.
>sr: [00:01.088811] rigol-ds: D5: off
>sr: [00:01.092859] scpi_vxi: Successfully sent SCPI command: ':LA:DIG6:DISP?'.
>sr: [00:01.095134] scpi: Got response: '0', length 1.
>sr: [00:01.095216] rigol-ds: D6: off
>sr: [00:01.098050] scpi_vxi: Successfully sent SCPI command: ':LA:DIG7:DISP?'.
>sr: [00:01.100271] scpi: Got response: '0', length 1.
>sr: [00:01.100324] rigol-ds: D7: off
>sr: [00:01.295587] scpi_vxi: Successfully sent SCPI command: ':LA:DIG8:DISP?'.
>sr: [00:01.327303] scpi: Got response: '0', length 1.
>sr: [00:01.327379] rigol-ds: D8: off
>sr: [00:01.330360] scpi_vxi: Successfully sent SCPI command: ':LA:DIG9:DISP?'.
>sr: [00:01.340494] scpi: Got response: '0', length 1.
>sr: [00:01.340571] rigol-ds: D9: off
>sr: [00:01.348572] scpi_vxi: Successfully sent SCPI command: ':LA:DIG10:DISP?'.
>sr: [00:01.507087] scpi: Got response: '0', length 1.
>sr: [00:01.507170] rigol-ds: D10: off
>sr: [00:01.510147] scpi_vxi: Successfully sent SCPI command: ':LA:DIG11:DISP?'.
>sr: [00:01.514645] scpi: Got response: '0', length 1.
>sr: [00:01.514728] rigol-ds: D11: off
>sr: [00:01.517422] scpi_vxi: Successfully sent SCPI command: ':LA:DIG12:DISP?'.
>sr: [00:01.521807] scpi: Got response: '0', length 1.
>sr: [00:01.521889] rigol-ds: D12: off
>sr: [00:01.524581] scpi_vxi: Successfully sent SCPI command: ':LA:DIG13:DISP?'.
>sr: [00:01.526834] scpi: Got response: '0', length 1.
>sr: [00:01.526889] rigol-ds: D13: off
>sr: [00:01.529626] scpi_vxi: Successfully sent SCPI command: ':LA:DIG14:DISP?'.
>sr: [00:01.532403] scpi: Got response: '0', length 1.
>sr: [00:01.532460] rigol-ds: D14: off
>sr: [00:01.535319] scpi_vxi: Successfully sent SCPI command: ':LA:DIG15:DISP?'.
>sr: [00:01.690317] scpi: Got response: '0', length 1.
>sr: [00:01.690400] rigol-ds: D15: off
>sr: [00:01.729281] scpi_vxi: Successfully sent SCPI command: ':TIM:SCAL?'.
>sr: [00:01.731709] scpi: Got response: '5.0000000e-03', length 13.
>sr: [00:01.731814] rigol-ds: Current timebase 0.005
>sr: [00:01.734353] scpi_vxi: Successfully sent SCPI command: ':CHAN1:PROB?'.
>sr: [00:01.736680] scpi: Got response: '1.000000e+01', length 12.
>sr: [00:01.739358] scpi_vxi: Successfully sent SCPI command: ':CHAN2:PROB?'.
>sr: [00:01.741714] scpi: Got response: '1.000000e+00', length 12.
>sr: [00:01.904169] scpi_vxi: Successfully sent SCPI command: ':CHAN3:PROB?'.
>sr: [00:01.907184] scpi: Got response: '1.000000e+01', length 12.
>sr: [00:01.910018] scpi_vxi: Successfully sent SCPI command: ':CHAN4:PROB?'.
>sr: [00:01.913416] scpi: Got response: '1.000000e+01', length 12.
>sr: [00:01.913493] rigol-ds: Current probe attenuation:
>sr: [00:01.913510] rigol-ds: CH1 10
>sr: [00:01.913536] rigol-ds: CH2 1
>sr: [00:01.913555] rigol-ds: CH3 10
>sr: [00:01.913572] rigol-ds: CH4 10
>sr: [00:01.916339] scpi_vxi: Successfully sent SCPI command: ':CHAN1:SCAL?'.
>sr: [00:01.918791] scpi: Got response: '5.000000e-01', length 12.
>sr: [00:01.923711] scpi_vxi: Successfully sent SCPI command: ':CHAN2:SCAL?'.
>sr: [00:01.927012] scpi: Got response: '5.000000e-01', length 12.
>sr: [00:01.929776] scpi_vxi: Successfully sent SCPI command: ':CHAN3:SCAL?'.
>sr: [00:01.932163] scpi: Got response: '2.000000e-02', length 12.
>sr: [00:01.934943] scpi_vxi: Successfully sent SCPI command: ':CHAN4:SCAL?'.
>sr: [00:01.937373] scpi: Got response: '2.000000e+00', length 12.
>sr: [00:01.937456] rigol-ds: Current vertical gain:
>sr: [00:01.937474] rigol-ds: CH1 0.5
>sr: [00:01.937499] rigol-ds: CH2 0.5
>sr: [00:01.937517] rigol-ds: CH3 0.02
>sr: [00:01.937536] rigol-ds: CH4 2
>sr: [00:02.093434] scpi_vxi: Successfully sent SCPI command: ':CHAN1:OFFS?'.
>sr: [00:02.097469] scpi: Got response: '0.000000e+00', length 12.
>sr: [00:02.136075] scpi_vxi: Successfully sent SCPI command: ':CHAN2:OFFS?'.
>sr: [00:02.141449] scpi: Got response: '0.000000e+00', length 12.
>sr: [00:02.144499] scpi_vxi: Successfully sent SCPI command: ':CHAN3:OFFS?'.
>sr: [00:02.146666] scpi: Got response: '1.320000e-02', length 12.
>sr: [00:02.340762] scpi_vxi: Successfully sent SCPI command: ':CHAN4:OFFS?'.
>sr: [00:02.377347] scpi: Got response: '-6.279999e+00', length 13.
>sr: [00:02.377452] rigol-ds: Current vertical offset:
>sr: [00:02.377470] rigol-ds: CH1 0
>sr: [00:02.377494] rigol-ds: CH2 0
>sr: [00:02.377510] rigol-ds: CH3 0.0132
>sr: [00:02.377530] rigol-ds: CH4 -6.28
>sr: [00:02.380192] scpi_vxi: Successfully sent SCPI command: ':CHAN1:COUP?'.
>sr: [00:02.384607] scpi: Got response: 'DC', length 2.
>sr: [00:02.394923] scpi_vxi: Successfully sent SCPI command: ':CHAN2:COUP?'.
>sr: [00:02.552181] scpi: Got response: 'DC', length 2.
>sr: [00:02.554894] scpi_vxi: Successfully sent SCPI command: ':CHAN3:COUP?'.
>sr: [00:02.557464] scpi: Got response: 'AC', length 2.
>sr: [00:02.570178] scpi_vxi: Successfully sent SCPI command: ':CHAN4:COUP?'.
>sr: [00:02.579387] scpi: Got response: 'DC', length 2.
>sr: [00:02.579459] rigol-ds: Current coupling:
>sr: [00:02.579474] rigol-ds: CH1 DC
>sr: [00:02.579504] rigol-ds: CH2 DC
>sr: [00:02.579519] rigol-ds: CH3 AC
>sr: [00:02.579533] rigol-ds: CH4 DC
>sr: [00:02.735816] scpi_vxi: Successfully sent SCPI command: ':TRIG:EDGE:SOUR?'.
>sr: [00:02.738469] scpi: Got response: 'CHAN1', length 5.
>sr: [00:02.738520] rigol-ds: Current trigger source CHAN1
>sr: [00:02.742361] scpi_vxi: Successfully sent SCPI command: ':TIM:OFFS?'.
>sr: [00:02.778174] scpi: Got response: '0.0000000e+00', length 13.
>sr: [00:02.778253] rigol-ds: Current horizontal trigger position 0
>sr: [00:02.787212] scpi_vxi: Successfully sent SCPI command: ':TRIG:EDGE:SLOP?'.
>sr: [00:02.789608] scpi: Got response: 'POS', length 3.
>sr: [00:02.789686] rigol-ds: Current trigger slope POS
>sr: [00:02.950580] scpi_vxi: Successfully sent SCPI command: ':TRIG:EDGE:LEV?'.
>sr: [00:02.953146] scpi: Got response: '9.200000e-01', length 12.
>sr: [00:02.953236] rigol-ds: Current trigger level 0.92
>sr: [00:02.953339] hwdriver: sr_config_set(): key 50002 (limit_frames) sdi 0x2098310 cg NULL -> uint64 1
>sr: [00:02.953433] session: Using thread-default main context.
>sr: [00:02.953454] session: Starting.
>sr: [00:02.953470] rigol-ds: handling channel CH1
>sr: [00:02.953496] rigol-ds: handling channel CH2
>sr: [00:02.953511] rigol-ds: handling channel CH3
>sr: [00:02.953526] rigol-ds: handling channel CH4
>sr: [00:02.953540] rigol-ds: handling channel D0
>sr: [00:02.953554] rigol-ds: handling channel D1
>sr: [00:02.953567] rigol-ds: handling channel D2
>sr: [00:02.953581] rigol-ds: handling channel D3
>sr: [00:02.953595] rigol-ds: handling channel D4
>sr: [00:02.953609] rigol-ds: handling channel D5
>sr: [00:02.953623] rigol-ds: handling channel D6
>sr: [00:02.953636] rigol-ds: handling channel D7
>sr: [00:02.953650] rigol-ds: handling channel D8
>sr: [00:02.953663] rigol-ds: handling channel D9
>sr: [00:02.953677] rigol-ds: handling channel D10
>sr: [00:02.953690] rigol-ds: handling channel D11
>sr: [00:02.953704] rigol-ds: handling channel D12
>sr: [00:02.953718] rigol-ds: handling channel D13
>sr: [00:02.953731] rigol-ds: handling channel D14
>sr: [00:02.953745] rigol-ds: handling channel D15
>sr: [00:02.956876] scpi_vxi: Successfully sent SCPI command: ':RUN'.
>sr: [00:02.959387] scpi_vxi: Successfully sent SCPI command: '*OPC?'.
>sr: [00:03.142942] scpi: Got response: '1', length 1.
>sr: [00:03.143056] std: rigol-ds: Starting acquisition.
>sr: [00:03.143074] std: rigol-ds: Sending SR_DF_HEADER packet.
>sr: [00:03.143100] session: Running transform module 'nop'.
>sr: [00:03.143116] transform/nop: Received packet of type 10000, passing on unmodified.
>sr: [00:03.143135] session: bus: Received SR_DF_HEADER packet.
>cli: Received SR_DF_HEADER.
>sr: [00:03.143288] hwdriver: sr_config_get(): key 30000 (samplerate) sdi 0x2098310 cg NULL -> uint64 20000
>sr: [00:03.143318] rigol-ds: Starting data capture for frameset 1 of 1
>sr: [00:03.185182] scpi_vxi: Successfully sent SCPI command: ':WAV:FORM BYTE'.
>sr: [00:03.418721] scpi_vxi: Successfully sent SCPI command: '*OPC?'.
>sr: [00:03.437531] scpi: Got response: '1', length 1.
>sr: [00:03.443265] scpi_vxi: Successfully sent SCPI command: ':WAV:MODE NORM'.
>sr: [00:03.445717] scpi_vxi: Successfully sent SCPI command: '*OPC?'.
>sr: [00:03.626996] scpi: Got response: '1', length 1.
>sr: [00:03.627071] session: Running transform module 'nop'.
>sr: [00:03.627087] transform/nop: Received packet of type 10005, passing on unmodified.
>sr: [00:03.627103] session: bus: Received SR_DF_FRAME_BEGIN packet.
>cli: Received SR_DF_FRAME_BEGIN.
>FRAME-BEGIN
>sr: [00:03.677381] rigol-ds: Sleeping for 50999 usecs instead of trigger-wait
>sr: [00:03.728556] rigol-ds: Starting reading data from channel 1
>sr: [00:03.908987] scpi_vxi: Successfully sent SCPI command: ':WAV:SOUR CHAN1'.
>sr: [00:03.913406] scpi_vxi: Successfully sent SCPI command: '*OPC?'.
>sr: [00:03.926096] scpi: Got response: '1', length 1.
>sr: [00:03.932372] scpi_vxi: Successfully sent SCPI command: ':WAV:MODE NORM'.
>sr: [00:03.936760] scpi_vxi: Successfully sent SCPI command: '*OPC?'.
>sr: [00:04.110727] scpi: Got response: '1', length 1.
>sr: [00:04.140984] scpi_vxi: Successfully sent SCPI command: ':WAV:YREF?'.
>sr: [00:04.144770] scpi: Got response: '127', length 3.
>sr: [00:04.153958] scpi_vxi: Successfully sent SCPI command: ':WAV:START 1'.
>sr: [00:04.311652] scpi_vxi: Successfully sent SCPI command: ':WAV:STOP 1200'.
>sr: [00:04.313959] scpi_vxi: Successfully sent SCPI command: ':WAV:DATA?'.
>sr: [00:04.314010] rigol-ds: New block header expected
>sr: [00:04.326255] scpi_vxi: Device read failed for 10.38.38.133 with error 15
>sr: [00:04.326335] rigol-ds: Read error while reading data header.
>sr: [00:04.326354] rigol-ds: Read error, aborting capture.
>sr: [00:04.326370] session: Running transform module 'nop'.
>sr: [00:04.326385] transform/nop: Received packet of type 10006, passing on unmodified.
>sr: [00:04.326401] session: bus: Received SR_DF_FRAME_END packet.
>cli: Received SR_DF_FRAME_END.
>FRAME-END
>sr: [00:04.326448] std: rigol-ds: Sending SR_DF_END packet.
>sr: [00:04.326464] session: Running transform module 'nop'.
>sr: [00:04.326479] transform/nop: Received packet of type 10001, passing on unmodified.
>sr: [00:04.326494] session: bus: Received SR_DF_END packet.
>cli: Received SR_DF_END.
>sr: [00:04.326548] session: fd_source_finalize: key 0xffffffffffffffff
>sr: [00:04.326594] session: Stopped.

Actions: View

Attachments on bug 933: 290 | 296 | 297 | 299 | 300