firmware update
dailijin
dailijin126 at 126.com
Thu Dec 17 21:30:59 PST 2015
Hi All,
Now I use GobiNet and GobiSerial driver, also use Sierra SDK to do modem firmware update, but always fail.
below are my test details.
1. test envirnment (TVU ONE platform)
system : Gentoo
kernel: Ubuntu 3.8
2. test steps:
1) disable dialing logic and qmi_wwan driver on TVU ONE
2) load drivers: GobiNet and GobiSerial (from S2.24N2.33)
3) copy Sierra SDK, upgrading program (from SLQS03.03.10) and upgrading file to TVU ONE, see below:
tvupack autosync # ls /opt/tvu/data/autosync/
firmware_old GobiNet.ko GobiSerial.ko mc7xxximgmgmthostx86_64 slqssdk
tvupack autosync # ls /opt/tvu/data/autosync/firmware_old/
9999999_9902509_SWI9X15C_05.05.26.02_00_TELSTRA_005.009_001-field.spk
4) update firmware via command "./mc7xxximgmgmthostx86_64 -d 0 -s ./slqssdk", below is details:
tvupack autosync # ./mc7xxximgmgmthostx86_64 -d 0 -s ./slqssdk
Running with device in disconnected mode
Please select one of the following options or press <Enter> to exit:
1. Display the information for the executing device image
2. Download a boot loader image to the device
3. Download a firmware image to the device
4. Download an NV item to the device
5. Display the information for a particular spk/cwe image located on the host
6. Display the information for a particular nvu image located on the host
Option: 1
CWE Image Fields
----------------
BOOT Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 18:38:46
AMSS Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 21:30:23
PRI Version: 9903554 05.02
SPKG CWE Image Fields
---------------------
Model ID: MC7304
BOOT Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 18:38:46
AMSS Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 21:30:23
SKU ID: 1102241
Package ID: 1102241_9903554_SWI9X15C_05.05.16.02_00_Generic_005.002_000
Carrier: 1
PRI version: 05.02
Please select one of the following options or press <Enter> to exit:
1. Display the information for the executing device image
2. Download a boot loader image to the device
3. Download a firmware image to the device
4. Download an NV item to the device
5. Display the information for a particular spk/cwe image located on the host
6. Display the information for a particular nvu image located on the host
Option: 3
Please specify the path (upto 510 Characters) or press <Enter> to return to the main menu: /opt/tvu/data/autosync/firmware_old
-- Active Device Image Before Download --
CWE Image Fields
----------------
BOOT Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 18:38:46
AMSS Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 21:30:23
PRI Version: 9903554 05.02
SPKG CWE Image Fields
---------------------
Model ID: MC7304
BOOT Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 18:38:46
AMSS Version: SWI9X15C_05.05.58.00 r27038 carmd-fwbuild1 2015/03/04 21:30:23
SKU ID: 1102241
Package ID: 1102241_9903554_SWI9X15C_05.05.16.02_00_Generic_005.002_000
Carrier: 1
PRI version: 05.02
-- Preparing to Download CWE image --
Downloading Firmware..................................................................................................................................................... (endlessly print "..." here)
5) capture system log related with firmware update as belows:
Dec 18 02:30:41 tvupack kernel: GobiNet: 2015-07-06/SWI_2.33
Dec 18 02:30:41 tvupack kernel: GobiNet 1-1.6.3:1.8 eth1: register 'GobiNet' at usb-0000:00:1d.0-1.6.3, GobiNet Ethernet Device, 2e:f5:d6:e8:f7:56
Dec 18 02:30:41 tvupack kernel: Ethernet mode
Dec 18 02:30:42 tvupack kernel: creating qcqmi0
Dec 18 02:30:42 tvupack kernel: USB Speed : USB 2.0
Dec 18 02:30:42 tvupack kernel: GobiNet 1-1.6.3:1.10 eth2: register 'GobiNet' at usb-0000:00:1d.0-1.6.3, GobiNet Ethernet Device, 2e:f5:d6:e8:f7:56
Dec 18 02:30:42 tvupack kernel: Ethernet mode
Dec 18 02:30:43 tvupack systemd-sysctl[5437]: Overwriting earlier assignment of kernel/sysrq in file '/usr/lib64/sysctl.d/60-gentoo.conf'.
Dec 18 02:30:43 tvupack systemd-sysctl[5436]: Overwriting earlier assignment of kernel/sysrq in file '/usr/lib64/sysctl.d/60-gentoo.conf'.
Dec 18 02:30:43 tvupack kernel: creating qcqmi1
Dec 18 02:30:43 tvupack kernel: USB Speed : USB 2.0
Dec 18 02:30:43 tvupack kernel: usbcore: registered new interface driver GobiNet
Dec 18 02:30:51 tvupack kernel: usbcore: registered new interface driver GobiSerial
Dec 18 02:30:51 tvupack kernel: usbserial: USB Serial support registered for GobiSerial
Dec 18 02:30:51 tvupack kernel: GobiSerial 1-1.6.3:1.0: GobiSerial converter detected
Dec 18 02:30:51 tvupack kernel: usb 1-1.6.3: GobiSerial converter now attached to ttyUSB1
Dec 18 02:30:51 tvupack kernel: GobiSerial 1-1.6.3:1.2: GobiSerial converter detected
Dec 18 02:30:51 tvupack kernel: usb 1-1.6.3: GobiSerial converter now attached to ttyUSB2
Dec 18 02:30:51 tvupack kernel: GobiSerial 1-1.6.3:1.3: GobiSerial converter detected
Dec 18 02:30:51 tvupack kernel: usb 1-1.6.3: GobiSerial converter now attached to ttyUSB3
Dec 18 02:30:51 tvupack kernel: GobiSerial: 2015-05-13/SWI_2.24:GobiSerial
Dec 18 02:30:53 tvupack ntpcheck.ntpd[872]: RTC clock written
Dec 18 02:30:53 tvupack ntpcheck.ntpd[872]: ntpcheck detected interface up 2: eth0 inet 10.12.32.2/18 brd 10.12.63.255 scope global eth0
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: SU:Process Started, version: SLQS03.03.10
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: SU:7 -> task started
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM:Task started
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: SU:12 -> task started
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: UDIAG:DS Shell launched
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: UDIAG:DS Shell launched
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process:
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: UDIAG:DS Shell launched
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process:
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB1 device found on USB Interface 0
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB1 device found on USB Interface 0
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB2 device found on USB Interface 2
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB2 device found on USB Interface 2
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB3 device found on USB Interface 3
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB3 device found on USB Interface 3
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdkusbscan/1425: 3 tty interfaces successfully scanned
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: qcqmi0 device found on USB Interface 8
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: qcqmi1 device found on USB Interface 10
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: qcqmi0 device found on USB Interface 8
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: qcqmi1 device found on USB Interface 10
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdkusbscan/1444: 2 qmi interfaces successfully scanned
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdkusbscan/1456: 5/6 interfaces successfully scanned
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdkusbscan: drivers ready!
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qm_map_instances_to_device_files: USEP_QMI: 0 USEP_QMI2: 1 USEP_QMI3: -1
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qm_map_instances_to_device_files: USEP_QMI4: -1 USEP_QMI5: -1 USEP_QMI6: -1
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qm_map_instances_to_device_files: USEP_QMI7: -1 USEP_QMI8: -1
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qm_ds_handle_app_dev_ready: devstate 1
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Notif: ch/Msgid/Msglen/Svctype: 1/0012/0/247
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Notif: ch/Msgid/Msglen/Svctype: 3/0012/0/247
Dec 18 02:31:18 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Notif: ch/Msgid/Msglen/Svctype: 5/0012/0/247
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f7/4/12
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f7/4/12
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->DCS: Request Sent : ipcch/xactionid: 0/0001
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK->DCS: Request Received : ipcch/xactionid: 0/0001
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0006/0/247
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f7/4/12
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f7/4/12
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->DCS: Request Sent : ipcch/xactionid: 0/0002
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK->DCS: Request Received : ipcch/xactionid: 0/0002
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0000/0/247
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f7/36/12
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f7/36/12
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->DCS: Request Sent : ipcch/xactionid: 0/0003
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK->DCS: Request Received : ipcch/xactionid: 0/0003
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/247
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0001/8/0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0001/8/0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: ~~~~qm_wds_get_session_id~~~
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 0, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 1, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 2, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 3, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 4, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 5, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 6, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 7, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0002/0/1
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0001/8/0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0001/8/0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: ~~~~qm_wds_get_session_id~~~
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 0, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 1, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 2, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 3, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 4, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 5, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 6, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 7, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0002/0/1
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0001/8/0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0001/8/0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: ~~~~qm_wds_get_session_id~~~
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 0, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 1, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 2, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 3, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 4, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 5, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 6, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: PID: 7, SIDv4: 0, SIDv6: 0
Dec 18 02:31:19 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0002/0/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:Launching QMI DS shell: service 2(DMS)
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process:
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QMURR1:Endpoint DS shell instance created
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 244, read 244 bytes
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/5556/241/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 160, read 160 bytes
Dec 18 02:31:26 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0023/157/1
Dec 18 02:31:53 tvupack ntpcheck.ntpd[872]: ntpcheck detected interface up 2: eth0 inet 10.12.32.2/18 brd 10.12.63.255 scope global eth0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdk_readdir
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 244, read 244 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/5556/241/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 160, read 160 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0023/157/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdk_readdir
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdk_readdir
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f7/4/12
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f7/4/12
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->DCS: Request Sent : ipcch/xactionid: 0/0004
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK->DCS: Request Received : ipcch/xactionid: 0/0004
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0006/0/247
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 23, read 23 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0022/20/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdk_readdir
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdk_readdir
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/48/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0001/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/4/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 92, read 92 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0047/89/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/86/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/86/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: psrwds_setfwPref: Firmware download Requested
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 20, read 20 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/0048/17/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/00f8/56/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/00f8/56/21
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:/opt/tvu/data/autosync/firmware_old/
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:MDM9x15 Selected: 1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:Image Mask: 0x3
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM-DCS:SDK<-DCS Resp: ch/Msgid/Msglen/Svctype: 0/0000/0/248
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/8/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/8/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 14, read 14 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/002e/11/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1416: Request: QMI Instance 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request received : ipcch/svctype/xactionlen/clientnum: 0/0002/8/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK->Mdm: request validated : ipcch/svctype/xactionlen/clientnum: 0/0002/8/1
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:qmqmireq/1529: WDS Request: Active Client 1, WDS Client 0
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: USB read: bytes2read = 14, read 14 bytes
Dec 18 02:31:54 tvupack slqssdk[6499]: SWI0 SDK Process: QM:SDK<-Mdm Resp: ch/Msgid/Msglen/client: 0/002e/11/1
Dec 18 02:32:02 tvupack kernel: usb 1-1.6.3: USB disconnect, device number 10
Dec 18 02:32:02 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB1 device on USB Interface 0 not found
Dec 18 02:32:02 tvupack slqssdk[6499]: SWI0 SDK Process: ttyUSB1 device on USB Interface 0 not found
Dec 18 02:32:02 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdkusbscan/1425: 2 tty interfaces successfully scanned
Dec 18 02:32:02 tvupack slqssdk[6499]: SWI0 SDK Process: swi_ossdkusbscan/1456: 4/6 interfaces successfully scanned
Dec 18 02:32:53 tvupack ntpcheck.ntpd[872]: ntpcheck detected interface up 2: eth0 inet 10.12.32.2/18 brd 10.12.63.255 scope global eth0
Dec 18 02:33:53 tvupack ntpcheck.ntpd[872]: ntpcheck detected interface up 2: eth0 inet 10.12.32.2/18 brd 10.12.63.255 scope global eth0
Dec 18 02:34:32 tvupack kernel: INFO: task khubd:35 blocked for more than 120 seconds.
Dec 18 02:34:32 tvupack kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec 18 02:34:32 tvupack kernel: khubd D 0000000000000000 0 35 2 0x00000000
Dec 18 02:34:32 tvupack kernel: ffff88011903da88 0000000000000046 ffff88011903da38 ffff88011903da38
Dec 18 02:34:32 tvupack kernel: ffff88011903dfd8 ffff88011903dfd8 ffff88011903dfd8 0000000000013f40
Dec 18 02:34:32 tvupack kernel: ffffffff81c15440 ffff8801197445c0 ffff880114339780 ffff88011746ad28
Dec 18 02:34:32 tvupack kernel: Call Trace:
Dec 18 02:34:32 tvupack kernel: [<ffffffff816e0669>] schedule+0x29/0x70
Dec 18 02:34:32 tvupack kernel: [<ffffffff816e091e>] schedule_preempt_disabled+0xe/0x10
Dec 18 02:34:32 tvupack kernel: [<ffffffff816df597>] __mutex_lock_slowpath+0xd7/0x150
Dec 18 02:34:32 tvupack kernel: [<ffffffff81510a11>] ? usb_kill_urb+0x31/0x40
Dec 18 02:34:32 tvupack kernel: [<ffffffff816df1aa>] mutex_lock+0x2a/0x50
Dec 18 02:34:32 tvupack kernel: [<ffffffffa03be0a1>] usb_serial_disconnect+0x31/0x110 [usbserial]
Dec 18 02:34:32 tvupack kernel: [<ffffffff81514ea0>] usb_unbind_interface+0x60/0x1b0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8146530c>] __device_release_driver+0x7c/0xe0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8146539c>] device_release_driver+0x2c/0x40
Dec 18 02:34:32 tvupack kernel: [<ffffffff81464d71>] bus_remove_device+0xe1/0x120
Dec 18 02:34:32 tvupack kernel: [<ffffffff814624f8>] device_del+0x118/0x1a0
Dec 18 02:34:32 tvupack kernel: [<ffffffff815128cf>] usb_disable_device+0xaf/0x200
Dec 18 02:34:32 tvupack kernel: [<ffffffff8150a364>] usb_disconnect+0xa4/0x1b0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8151084e>] ? usb_alloc_urb+0x1e/0x50
Dec 18 02:34:32 tvupack kernel: [<ffffffff8150b609>] hub_port_connect_change+0xc9/0x7d0
Dec 18 02:34:32 tvupack kernel: [<ffffffff81511e07>] ? usb_control_msg+0xf7/0x120
Dec 18 02:34:32 tvupack kernel: [<ffffffff8150bf44>] hub_events+0x234/0x610
Dec 18 02:34:32 tvupack kernel: [<ffffffff81045cd9>] ? default_spin_lock_flags+0x9/0x10
Dec 18 02:34:32 tvupack kernel: [<ffffffff8150c355>] hub_thread+0x35/0x1a0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8107fc10>] ? add_wait_queue+0x60/0x60
Dec 18 02:34:32 tvupack kernel: [<ffffffff8150c320>] ? hub_events+0x610/0x610
Dec 18 02:34:32 tvupack kernel: [<ffffffff8107f050>] kthread+0xc0/0xd0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8107ef90>] ? flush_kthread_worker+0xb0/0xb0
Dec 18 02:34:32 tvupack kernel: [<ffffffff816ea36c>] ret_from_fork+0x7c/0xb0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8107ef90>] ? flush_kthread_worker+0xb0/0xb0
Dec 18 02:34:32 tvupack kernel: INFO: task python2:5654 blocked for more than 120 seconds.
Dec 18 02:34:32 tvupack kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec 18 02:34:32 tvupack kernel: python2 D 0000000000000002 0 5654 1617 0x00000004
Dec 18 02:34:32 tvupack kernel: ffff880102c51958 0000000000000086 ffff880102c51978 ffff88011977a9a0
Dec 18 02:34:32 tvupack kernel: ffff880102c51fd8 ffff880102c51fd8 ffff880102c51fd8 0000000000013f40
Dec 18 02:34:32 tvupack kernel: ffff88010b049740 ffff8800cf8f1740 ffff8800cf8f1740 ffff88011746ad28
Dec 18 02:34:32 tvupack kernel: Call Trace:
Dec 18 02:34:32 tvupack kernel: [<ffffffff816e0669>] schedule+0x29/0x70
Dec 18 02:34:32 tvupack kernel: [<ffffffff816e091e>] schedule_preempt_disabled+0xe/0x10
Dec 18 02:34:32 tvupack kernel: [<ffffffff816df597>] __mutex_lock_slowpath+0xd7/0x150
Dec 18 02:34:32 tvupack kernel: [<ffffffff8151084e>] ? usb_alloc_urb+0x1e/0x50
Dec 18 02:34:32 tvupack kernel: [<ffffffff816df1aa>] mutex_lock+0x2a/0x50
Dec 18 02:34:32 tvupack kernel: [<ffffffffa02a357c>] Gobi_dtr_rts+0x6c/0x130 [GobiSerial]
Dec 18 02:34:32 tvupack kernel: [<ffffffffa03bf725>] ? usb_serial_generic_submit_read_urb+0x25/0x30 [usbserial]
Dec 18 02:34:32 tvupack kernel: [<ffffffffa03bd58f>] serial_dtr_rts+0x7f/0x90 [usbserial]
Dec 18 02:34:32 tvupack kernel: [<ffffffff81429044>] tty_port_block_til_ready+0x174/0x320
Dec 18 02:34:32 tvupack kernel: [<ffffffff8107fc10>] ? add_wait_queue+0x60/0x60
Dec 18 02:34:32 tvupack kernel: [<ffffffffa03bd4f2>] ? serial_activate+0x82/0xa0 [usbserial]
Dec 18 02:34:32 tvupack kernel: [<ffffffff814293eb>] tty_port_open+0xbb/0xe0
Dec 18 02:34:32 tvupack kernel: [<ffffffff816e159e>] ? _raw_spin_lock+0xe/0x20
Dec 18 02:34:32 tvupack kernel: [<ffffffffa03bdad2>] serial_open+0x22/0x30 [usbserial]
Dec 18 02:34:32 tvupack kernel: [<ffffffff81420fb2>] tty_open+0x172/0x420
Dec 18 02:34:32 tvupack kernel: [<ffffffff8146848b>] ? kobj_lookup+0x10b/0x170
Dec 18 02:34:32 tvupack kernel: [<ffffffff8119ff52>] chrdev_open+0xb2/0x1a0
Dec 18 02:34:32 tvupack kernel: [<ffffffff8119fea0>] ? cdev_put+0x30/0x30
Dec 18 02:34:32 tvupack kernel: [<ffffffff8119954e>] do_dentry_open+0x21e/0x2a0
Dec 18 02:34:32 tvupack kernel: [<ffffffff811999c9>] vfs_open+0x49/0x50
Dec 18 02:34:32 tvupack kernel: [<ffffffff811a7e06>] do_last+0x246/0x820
Dec 18 02:34:32 tvupack kernel: [<ffffffff8131337c>] ? apparmor_file_alloc_security+0x2c/0x60
Dec 18 02:34:32 tvupack kernel: [<ffffffff811aa333>] path_openat+0xb3/0x4d0
Dec 18 02:34:32 tvupack kernel: [<ffffffff811ab152>] do_filp_open+0x42/0xa0
Dec 18 02:34:32 tvupack kernel: [<ffffffff811b8dd5>] ? __alloc_fd+0xe5/0x170
Dec 18 02:34:32 tvupack kernel: [<ffffffff8119adaa>] do_sys_open+0xfa/0x250
Dec 18 02:34:32 tvupack kernel: [<ffffffff8119af21>] sys_open+0x21/0x30
Dec 18 02:34:32 tvupack kernel: [<ffffffff816ea41d>] system_call_fastpath+0x1a/0x1f
Dec 18 02:34:53 tvupack ntpcheck.ntpd[872]: ntpcheck detected interface up 2: eth0 inet 10.12.32.2/18 brd 10.12.63.255 scope global eth0
Dec 18 02:35:53 tvupack ntpcheck.ntpd[872]: ntpcheck detected interface up 2: eth0 inet 10.12.32.2/18 brd 10.12.63.255 scope global eth0
Dec 18 02:36:32 tvupack kernel: INFO: task khubd:35 blocked for more than 120 seconds.
Dec 18 02:36:32 tvupack kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec 18 02:36:32 tvupack kernel: khubd D 0000000000000000 0 35 2 0x00000000
Dec 18 02:36:32 tvupack kernel: ffff88011903da88 0000000000000046 ffff88011903da38 ffff88011903da38
Dec 18 02:36:32 tvupack kernel: ffff88011903dfd8 ffff88011903dfd8 ffff88011903dfd8 0000000000013f40
Dec 18 02:36:32 tvupack kernel: ffffffff81c15440 ffff8801197445c0 ffff880114339780 ffff88011746ad28
Dec 18 02:36:32 tvupack kernel: Call Trace:
Dec 18 02:36:32 tvupack kernel: [<ffffffff816e0669>] schedule+0x29/0x70
Dec 18 02:36:32 tvupack kernel: [<ffffffff816e091e>] schedule_preempt_disabled+0xe/0x10
Dec 18 02:36:32 tvupack kernel: [<ffffffff816df597>] __mutex_lock_slowpath+0xd7/0x150
Dec 18 02:36:32 tvupack kernel: [<ffffffff81510a11>] ? usb_kill_urb+0x31/0x40
Dec 18 02:36:32 tvupack kernel: [<ffffffff816df1aa>] mutex_lock+0x2a/0x50
Dec 18 02:36:32 tvupack kernel: [<ffffffffa03be0a1>] usb_serial_disconnect+0x31/0x110 [usbserial]
Dec 18 02:36:32 tvupack kernel: [<ffffffff81514ea0>] usb_unbind_interface+0x60/0x1b0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8146530c>] __device_release_driver+0x7c/0xe0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8146539c>] device_release_driver+0x2c/0x40
Dec 18 02:36:32 tvupack kernel: [<ffffffff81464d71>] bus_remove_device+0xe1/0x120
Dec 18 02:36:32 tvupack kernel: [<ffffffff814624f8>] device_del+0x118/0x1a0
Dec 18 02:36:32 tvupack kernel: [<ffffffff815128cf>] usb_disable_device+0xaf/0x200
Dec 18 02:36:32 tvupack kernel: [<ffffffff8150a364>] usb_disconnect+0xa4/0x1b0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8151084e>] ? usb_alloc_urb+0x1e/0x50
Dec 18 02:36:32 tvupack kernel: [<ffffffff8150b609>] hub_port_connect_change+0xc9/0x7d0
Dec 18 02:36:32 tvupack kernel: [<ffffffff81511e07>] ? usb_control_msg+0xf7/0x120
Dec 18 02:36:32 tvupack kernel: [<ffffffff8150bf44>] hub_events+0x234/0x610
Dec 18 02:36:32 tvupack kernel: [<ffffffff81045cd9>] ? default_spin_lock_flags+0x9/0x10
Dec 18 02:36:32 tvupack kernel: [<ffffffff8150c355>] hub_thread+0x35/0x1a0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8107fc10>] ? add_wait_queue+0x60/0x60
Dec 18 02:36:32 tvupack kernel: [<ffffffff8150c320>] ? hub_events+0x610/0x610
Dec 18 02:36:32 tvupack kernel: [<ffffffff8107f050>] kthread+0xc0/0xd0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8107ef90>] ? flush_kthread_worker+0xb0/0xb0
Dec 18 02:36:32 tvupack kernel: [<ffffffff816ea36c>] ret_from_fork+0x7c/0xb0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8107ef90>] ? flush_kthread_worker+0xb0/0xb0
Dec 18 02:36:32 tvupack kernel: INFO: task python2:5654 blocked for more than 120 seconds.
Dec 18 02:36:32 tvupack kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Dec 18 02:36:32 tvupack kernel: python2 D 0000000000000002 0 5654 1617 0x00000004
Dec 18 02:36:32 tvupack kernel: ffff880102c51958 0000000000000086 ffff880102c51978 ffff88011977a9a0
Dec 18 02:36:32 tvupack kernel: ffff880102c51fd8 ffff880102c51fd8 ffff880102c51fd8 0000000000013f40
Dec 18 02:36:32 tvupack kernel: ffff88010b049740 ffff8800cf8f1740 ffff8800cf8f1740 ffff88011746ad28
Dec 18 02:36:32 tvupack kernel: Call Trace:
Dec 18 02:36:32 tvupack kernel: [<ffffffff816e0669>] schedule+0x29/0x70
Dec 18 02:36:32 tvupack kernel: [<ffffffff816e091e>] schedule_preempt_disabled+0xe/0x10
Dec 18 02:36:32 tvupack kernel: [<ffffffff816df597>] __mutex_lock_slowpath+0xd7/0x150
Dec 18 02:36:32 tvupack kernel: [<ffffffff8151084e>] ? usb_alloc_urb+0x1e/0x50
Dec 18 02:36:32 tvupack kernel: [<ffffffff816df1aa>] mutex_lock+0x2a/0x50
Dec 18 02:36:32 tvupack kernel: [<ffffffffa02a357c>] Gobi_dtr_rts+0x6c/0x130 [GobiSerial]
Dec 18 02:36:32 tvupack kernel: [<ffffffffa03bf725>] ? usb_serial_generic_submit_read_urb+0x25/0x30 [usbserial]
Dec 18 02:36:32 tvupack kernel: [<ffffffffa03bd58f>] serial_dtr_rts+0x7f/0x90 [usbserial]
Dec 18 02:36:32 tvupack kernel: [<ffffffff81429044>] tty_port_block_til_ready+0x174/0x320
Dec 18 02:36:32 tvupack kernel: [<ffffffff8107fc10>] ? add_wait_queue+0x60/0x60
Dec 18 02:36:32 tvupack kernel: [<ffffffffa03bd4f2>] ? serial_activate+0x82/0xa0 [usbserial]
Dec 18 02:36:32 tvupack kernel: [<ffffffff814293eb>] tty_port_open+0xbb/0xe0
Dec 18 02:36:32 tvupack kernel: [<ffffffff816e159e>] ? _raw_spin_lock+0xe/0x20
Dec 18 02:36:32 tvupack kernel: [<ffffffffa03bdad2>] serial_open+0x22/0x30 [usbserial]
Dec 18 02:36:32 tvupack kernel: [<ffffffff81420fb2>] tty_open+0x172/0x420
Dec 18 02:36:32 tvupack kernel: [<ffffffff8146848b>] ? kobj_lookup+0x10b/0x170
Dec 18 02:36:32 tvupack kernel: [<ffffffff8119ff52>] chrdev_open+0xb2/0x1a0
Dec 18 02:36:32 tvupack kernel: [<ffffffff8119fea0>] ? cdev_put+0x30/0x30
Dec 18 02:36:32 tvupack kernel: [<ffffffff8119954e>] do_dentry_open+0x21e/0x2a0
Dec 18 02:36:32 tvupack kernel: [<ffffffff811999c9>] vfs_open+0x49/0x50
Dec 18 02:36:32 tvupack kernel: [<ffffffff811a7e06>] do_last+0x246/0x820
Dec 18 02:36:32 tvupack kernel: [<ffffffff8131337c>] ? apparmor_file_alloc_security+0x2c/0x60
Dec 18 02:36:32 tvupack kernel: [<ffffffff811aa333>] path_openat+0xb3/0x4d0
Dec 18 02:36:32 tvupack kernel: [<ffffffff811ab152>] do_filp_open+0x42/0xa0
Dec 18 02:36:32 tvupack kernel: [<ffffffff811b8dd5>] ? __alloc_fd+0xe5/0x170
Dec 18 02:36:32 tvupack kernel: [<ffffffff8119adaa>] do_sys_open+0xfa/0x250
Dec 18 02:36:32 tvupack kernel: [<ffffffff8119af21>] sys_open+0x21/0x30
Dec 18 02:36:32 tvupack kernel: [<ffffffff816ea41d>] system_call_fastpath+0x1a/0x1f
From above logs, could someone find some clues about this issue? what should I do in next step?
Thanks very much.
--
Best Regards,
Dai,Lijin
At 2015-12-15 16:41:01, "Bjørn Mork" <bjorn at mork.no> wrote:
>David McCullough <david.mccullough at accelerated.com> writes:
>
>> Hi dailijin,
>>
>> While the discussion about implementing the firmware update is great and
>> there is nothing I would like more than an OSS solution I thought I would
>> let you know how we tackled it.
>>
>> basically we load qmi_wwan and qserial as modules, when we want to do a
>> firmware update we unload those and load the Sierra GobiNet and GobiSerial
>> drivers to do the update.
>
>FWIW, this is what I've done so far as well. I am happy to say that the
>quality of the GobiNet and GobiSerial drivers now is so good that
>building them against the newest kernels is trouble free. Just type
>"make" and off you go. Or "make RAWIP=1" if you want to build the
>GobiNet driver with MC74xx support (even if you only intend it for
>firmware updatng - probing will fail without it).
>
>I have more problems with the Gobi userspace part (i.e. the SDK).
>Mostly because important parts of it is binary only, and only built for
>a few specific platforms. But then again, if you've built one of the
>firmware upload applications and are happy with it, there are rarely any
>reason to rebuild it.
>
>> To ensure during the numerous resets etc that happen the correct drivers
>> are loaded we just prevent auto loading on those 4 modules and manage it
>> as needed.
>
>I usually manage this with module blacklisting, temporarily changing
>the blacklist when I prepare for a firmware update.
>
>But one warning wrt managing multiple modems this way: The SDK now has
>support for multiple modems, but I suspect it might make false
>assumptions about stable probing order. In any case, the last time I
>played with this, I managed to update the "wrong" modem. I had two
>identical modems with different firmware versions plugged in, and
>intended to update one specific of them. To my surprise the other modem
>was updated instead of the one I thought I was looking at.
>
>Assuming PEBKAC, but still...
>
>Probably a weird usecase. Most people likely want to update all
>identical hardware to the same firmware version. But I'd still prefer
>an update application which would verify matching modem serial number in
>application and boot modes. Or at least matching USB port.
>
>
>Bjørn
>_______________________________________________
>libqmi-devel mailing list
>libqmi-devel at lists.freedesktop.org
>http://lists.freedesktop.org/mailman/listinfo/libqmi-devel
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freedesktop.org/archives/libqmi-devel/attachments/20151218/73ca2882/attachment-0001.html>
More information about the libqmi-devel
mailing list