Sometimes data connection works, and sometimes it doesn't

Ruben Fagundo (rfagundo@kamping.com)
Sat, 12 Dec 1998 20:38:01 -0500
Messages sorted by:[ date ][ thread ][ subject ][ author ]
I setup mgetty on my friends PC running Linux.  He has a Motorolla 28.8 internal
bitsurfer modem. Faxes go in and out just fine, even better than my 3COM 56K
modem.

I have tried connecting to his PC on a few occasions as  a PPP user, and I get
inconsistent connections.  Before I dismiss this as just the way it is, I just
wanted to ask if anyone in this newsgroup has experienced anything similar.  I
compared the successful login, and the failed login, and the difference occurrs
in this way:

Failed login reports:
12/12 14:59:50 dem  send: ATA[0d]
12/12 14:59:50 dem  waiting for ``CONNECT''
12/12 14:59:50 dem   got: ATA[0d]
12/12 14:59:50 dem    CND: ATA[0d][0a]+FHNG:
12/12 15:00:46 dem  found action string: ``+FHNG:''
12/12 15:00:46 ##### failed A_FAIL dev=modem, pid=94, caller='none', conn='',
name=''
12/12 15:00:46 dem   removing lock file

vs. a Succesfull connection ...

12/12 15:31:00 dem  send: ATA[0d]
12/12 15:31:00 dem  waiting for ``CONNECT''
12/12 15:31:00 dem   got: ATA[0d]
12/12 15:31:00 dem    CND: ATA[0d][0a]DATA[0d]
12/12 15:31:21 dem    CND: DATA[0a][0d][0a]CONNECT ** found **
12/12 15:31:22 dem  send: 
12/12 15:31:22 dem  waiting for ``_''
12/12 15:31:22 dem   got:  38400[0d]
12/12 15:31:22 dem    CND: CONNECT 38400
12/12 15:31:22 dem    CND: found: 38400[0a] ** found **
12/12 15:31:22 dem   waiting for line to clear (VTIME), read: [0d]
12/12 15:31:22 dem    looking for utmp entry... (my PID: 103)

Below is a more complete log of both a failed and successfull connection in the
order which they occurred. Any comments or suggestions appreciated.

Ruben

# failed login log begins below, followed by successful login log ###

12/12 14:32:10 dem  waiting...
12/12 14:59:50 dem    select returned 1
12/12 14:59:50 dem   checking lockfiles, locking the line
12/12 14:59:50 dem   makelock(modem) called
12/12 14:59:50 dem   do_makelock: lock='/var/lock/LCK..modem'
12/12 14:59:50 dem   lock made
12/12 14:59:50 dem  wfr: waiting for ``RING''
12/12 14:59:50 dem   got: [0a][0d][0a]RING[0d]
12/12 14:59:50 dem    CND: RING
12/12 14:59:50 dem   wfr: rc=0, drn=0
12/12 14:59:50 dem    CND: check no: 'none'
12/12 14:59:50 dem  send: ATA[0d]
12/12 14:59:50 dem  waiting for ``CONNECT''
12/12 14:59:50 dem   got: ATA[0d]
12/12 14:59:50 dem    CND: ATA[0d][0a]+FHNG:
12/12 15:00:46 dem  found action string: ``+FHNG:''
12/12 15:00:46 ##### failed A_FAIL dev=modem, pid=94, caller='none', conn='',
name=''

12/12 15:00:46 dem   removing lock file
--
12/12 15:00:46 dem  mgetty: experimental test release 1.1.18-Sep11
12/12 15:00:46 dem   mgetty.c compiled at Oct 26 1998, 19:24:36
12/12 15:00:46 dem   user id: 0, parent pid: 1
12/12 15:00:46 dem   reading configuration data for port 'modem'
12/12 15:00:46 dem   reading /etc/mgetty+sendfax/mgetty.config...
12/12 15:00:46 dem   conf lib: read: 'debug 9'
12/12 15:00:46 dem   conf lib: read: 'fax-id 617-436-5141'
12/12 15:00:46 dem   conf lib: read: 'speed 38400'
12/12 15:00:46 dem   conf lib: read: 'port modem'
12/12 15:00:46 dem   section: port modem, **found**
12/12 15:00:46 dem   conf lib: read: 'init-chat "" "ATS0=0Q0&D3&C1 OK"'
12/12 15:00:46 dem   key: 'speed', type=0, flags=3, data=38400
12/12 15:00:46 dem   key: 'switchbd', type=0, flags=1, data=0
12/12 15:00:46 dem   key: 'direct', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'blocking', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'port-owner', type=1, flags=1, data=uucp
12/12 15:00:46 dem   key: 'port-group', type=1, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'port-mode', type=0, flags=1, data=432
12/12 15:00:46 dem   key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/12 15:00:46 dem   key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/12 15:00:46 dem   key: 'data-only', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'fax-only', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'modem-type', type=1, flags=1, data=auto
12/12 15:00:46 dem   key: 'modem-quirks', type=0, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'init-chat', type=2, flags=3, data= ATS0=0Q0&D3&C1 OK 
12/12 15:00:46 dem   key: 'force-init-chat', type=2, flags=1, data=
\d\d\d\d+++\d\d\d
\dATQ0V1H0 OK 
12/12 15:00:46 dem   key: 'post-init-chat', type=2, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'modem-check-time', type=0, flags=1, data=3600
12/12 15:00:46 dem   key: 'rings', type=0, flags=1, data=1
12/12 15:00:46 dem   key: 'msn-list', type=2, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'cnd-program', type=1, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'answer-chat', type=2, flags=1, data= ATA CONNECT \c 
 
12/12 15:00:46 dem   key: 'answer-chat-timeout', type=0, flags=1, data=80
12/12 15:00:46 dem   key: 'autobauding', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'ringback', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'ringback-time', type=0, flags=1, data=30
12/12 15:00:46 dem   key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/12 15:00:46 dem   key: 'issue-file', type=1, flags=1, data=/etc/issue
12/12 15:00:46 dem   key: 'prompt-waittime', type=0, flags=1, data=500
12/12 15:00:46 dem   key: 'login-prompt', type=1, flags=1, data=@!login: 
12/12 15:00:46 dem   key: 'login-time', type=0, flags=1, data=30
12/12 15:00:46 dem   key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/12 15:00:46 dem   key: 'fax-id', type=1, flags=3, data=617-436-5141
12/12 15:00:46 dem   key: 'fax-min-speed', type=0, flags=1, data=0
12/12 15:00:46 dem   key: 'fax-max-speed', type=0, flags=1, data=14400
12/12 15:00:46 dem   key: 'fax-server-file', type=1, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'diskspace', type=0, flags=1, data=1024
12/12 15:00:46 dem   key: 'notify', type=0, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'fax-owner', type=1, flags=1, data=uucp
12/12 15:00:46 dem   key: 'fax-group', type=1, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'fax-mode', type=0, flags=1, data=432
12/12 15:00:46 dem   key: 'debug', type=0, flags=2, data=9
12/12 15:00:46 dem   key: 'statistics-chat', type=2, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'statistics-file', type=1, flags=0, data=(empty)
12/12 15:00:46 dem   key: 'gettydefs', type=1, flags=1, data=n
12/12 15:00:46 dem   key: 'term', type=1, flags=0, data=(empty)
12/12 15:00:46 dem  check for lockfiles
12/12 15:00:46 dem   checklock: stat failed, no file
12/12 15:00:46 dem  locking the line
12/12 15:00:46 dem   makelock(modem) called
12/12 15:00:46 dem   do_makelock: lock='/var/lock/LCK..modem'
12/12 15:00:46 dem   lock made
12/12 15:00:46 dem   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
12/12 15:00:46 dem  WARNING: DCD line still active, check modem settings (AT&Dx)
12/12 15:00:46 dem  lowering DTR to reset Modem
12/12 15:00:47 dem   tss: set speed to 38400 (017)
12/12 15:00:47 dem   tio_set_flow_control( HARD )
12/12 15:00:47 dem   waiting for line to clear (VTIME), read: 
12/12 15:00:47 dem  send: ATS0=0Q0&D3&C1 OK[0d]
12/12 15:00:47 dem  mdm_send: 'ATI'
12/12 15:00:47 dem    got:ATS0=0Q0&D3&C1 OK[0d]
12/12 15:00:47 dem   mdm_gis: string 1: 'ATS0=0Q0&D3&C1 OK'
12/12 15:00:47 dem    got:[0d][0a]ERROR[0d]
12/12 15:00:47 dem   mdm_identify: string 'ATS0=0Q0&D3&C1 OK'
12/12 15:00:47 dem  non-numeric ID string: 'ATS0=0Q0&D3&C1 OK'
12/12 15:00:47 dem  mdm_send: 'AT+FCLASS=2.0'
12/12 15:00:47 dem    got:[0a]AT+FCLASS=2.0[0d]
12/12 15:00:47 dem   mdm_command: string 'AT+FCLASS=2.0'
12/12 15:00:47 dem    got:[0d][0a]ERROR[0d]
12/12 15:00:47 dem   mdm_command: string 'ERROR' -> ERROR
12/12 15:00:47 dem  mdm_send: 'AT+FCLASS=2'
12/12 15:00:47 dem    got:[0a]AT+FCLASS=2[0d]
12/12 15:00:47 dem   mdm_command: string 'AT+FCLASS=2'
12/12 15:00:47 dem    got:[0d][0a]OK[0d]
12/12 15:00:48 dem   mdm_command: string 'OK' -> OK
12/12 15:00:48 dem  mdm_send: 'AT+FCLASS=0'
12/12 15:00:48 dem    got:[0a]AT+FCLASS=0[0d]
12/12 15:00:48 dem   mdm_command: string 'AT+FCLASS=0'
12/12 15:00:48 dem    got:[0d][0a]OK[0d]
12/12 15:00:48 dem   mdm_command: string 'OK' -> OK
12/12 15:00:48 dem  mdm_send: 'AT+FAA=1;+FCR=1'
12/12 15:00:48 dem    got:[0a]AT+FAA=1;+FCR=1[0d]
12/12 15:00:48 dem   mdm_command: string 'AT+FAA=1;+FCR=1'
12/12 15:00:48 dem    got:[0d][0a]OK[0d]
12/12 15:00:48 dem   mdm_command: string 'OK' -> OK
12/12 15:00:48 dem  mdm_send: 'AT+FBOR=0'
12/12 15:00:48 dem    got:[0a]AT+FBOR=0[0d]
12/12 15:00:48 dem   mdm_command: string 'AT+FBOR=0'
12/12 15:00:48 dem    got:[0d][0a]OK[0d]
12/12 15:00:48 dem   mdm_command: string 'OK' -> OK
12/12 15:00:48 dem  mdm_send: 'AT+FLID="617-436-5141"'
12/12 15:00:48 dem    got:[0a]AT+FLID="617-436-5141"[0d]
12/12 15:00:48 dem   mdm_command: string 'AT+FLID="617-436-5141"'
12/12 15:00:48 dem    got:[0d][0a]OK[0d]
12/12 15:00:48 dem   mdm_command: string 'OK' -> OK
12/12 15:00:48 dem  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
12/12 15:00:48 dem    got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
12/12 15:00:48 dem   mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
12/12 15:00:48 dem    got:[0d][0a]OK[0d]
12/12 15:00:48 dem   mdm_command: string 'OK' -> OK
12/12 15:00:48 dem   waiting for line to clear (VTIME), read: 
12/12 15:00:49 dem   removing lock file
12/12 15:00:49 dem  waiting...

# Successful login log begins below ######

12/12 15:31:00 dem    select returned 1
12/12 15:31:00 dem   checking lockfiles, locking the line
12/12 15:31:00 dem   makelock(modem) called
12/12 15:31:00 dem   do_makelock: lock='/var/lock/LCK..modem'
12/12 15:31:00 dem   lock made
12/12 15:31:00 dem  wfr: waiting for ``RING''
12/12 15:31:00 dem   got: [0a][0d][0a]RING[0d]
12/12 15:31:00 dem    CND: RING
12/12 15:31:00 dem   wfr: rc=0, drn=0
12/12 15:31:00 dem    CND: check no: 'none'
12/12 15:31:00 dem  send: ATA[0d]
12/12 15:31:00 dem  waiting for ``CONNECT''
12/12 15:31:00 dem   got: ATA[0d]
12/12 15:31:00 dem    CND: ATA[0d][0a]DATA[0d]
12/12 15:31:21 dem    CND: DATA[0a][0d][0a]CONNECT ** found **
12/12 15:31:22 dem  send: 
12/12 15:31:22 dem  waiting for ``_''
12/12 15:31:22 dem   got:  38400[0d]
12/12 15:31:22 dem    CND: CONNECT 38400
12/12 15:31:22 dem    CND: found: 38400[0a] ** found **
12/12 15:31:22 dem   waiting for line to clear (VTIME), read: [0d]
12/12 15:31:22 dem    looking for utmp entry... (my PID: 103)
12/12 15:31:22 dem   utmp + wtmp entry made
12/12 15:31:22 dem   tio_set_flow_control( HARD )
12/12 15:31:22 dem   print welcome banner (/etc/issue)
12/12 15:31:22 dem   getlogname (AUTO_PPP), read:pppuser[0d]
12/12 15:31:23 dem   input finished with '\r', setting ICRNL ONLCR
12/12 15:31:23 dem   tio_get_rs232_lines: status: RTS CTS DSR DTR DCD
12/12 15:31:23 dem    login: use login config file
/etc/mgetty+sendfax/login.config
12/12 15:31:23 dem   match: user='ppp_user1', key='/AutoPPP/'
12/12 15:31:23 dem   match: user='ppp_user1', key='*'*** hit!
12/12 15:31:23 dem   calling login: cmd='/bin/login', argv[]='login ppp_user1'
12/12 15:31:23 ##### data dev=modem, pid=103, caller='none', conn='38400',
name='', cmd='/bin/login', user='pppuser'

12/12 15:31:23 dem   setenv: 'CALLER_ID=none'
12/12 15:31:23 dem   setenv: 'CONNECT=38400'
--
12/12 15:32:05 dem  mgetty: experimental test release 1.1.18-Sep11
12/12 15:32:05 dem   mgetty.c compiled at Oct 26 1998, 19:24:36
12/12 15:32:05 dem   user id: 0, parent pid: 1
12/12 15:32:05 dem   reading configuration data for port 'modem'
12/12 15:32:05 dem   reading /etc/mgetty+sendfax/mgetty.config...
12/12 15:32:05 dem   conf lib: read: 'debug 9'
12/12 15:32:05 dem   conf lib: read: 'fax-id 617-436-5141'
12/12 15:32:05 dem   conf lib: read: 'speed 38400'
12/12 15:32:05 dem   conf lib: read: 'port modem'
12/12 15:32:05 dem   section: port modem, **found**
12/12 15:32:05 dem   conf lib: read: 'init-chat "" "ATS0=0Q0&D3&C1 OK"'
12/12 15:32:05 dem   key: 'speed', type=0, flags=3, data=38400
12/12 15:32:05 dem   key: 'switchbd', type=0, flags=1, data=0
12/12 15:32:05 dem   key: 'direct', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'blocking', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'port-owner', type=1, flags=1, data=uucp
12/12 15:32:05 dem   key: 'port-group', type=1, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'port-mode', type=0, flags=1, data=432
12/12 15:32:05 dem   key: 'toggle-dtr', type=3, flags=1, data=TRUE
12/12 15:32:05 dem   key: 'toggle-dtr-waittime', type=0, flags=1, data=500
12/12 15:32:05 dem   key: 'data-only', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'fax-only', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'modem-type', type=1, flags=1, data=auto
12/12 15:32:05 dem   key: 'modem-quirks', type=0, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'init-chat', type=2, flags=3, data= ATS0=0Q0&D3&C1 OK 
12/12 15:32:05 dem   key: 'force-init-chat', type=2, flags=1, data=
\d\d\d\d+++\d\d\d
\dATQ0V1H0 OK 
12/12 15:32:05 dem   key: 'post-init-chat', type=2, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'modem-check-time', type=0, flags=1, data=3600
12/12 15:32:05 dem   key: 'rings', type=0, flags=1, data=1
12/12 15:32:05 dem   key: 'msn-list', type=2, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'get-cnd-chat', type=2, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'cnd-program', type=1, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'answer-chat', type=2, flags=1, data= ATA CONNECT \c 
 
12/12 15:32:05 dem   key: 'answer-chat-timeout', type=0, flags=1, data=80
12/12 15:32:05 dem   key: 'autobauding', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'ringback', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'ringback-time', type=0, flags=1, data=30
12/12 15:32:05 dem   key: 'ignore-carrier', type=3, flags=1, data=FALSE
12/12 15:32:05 dem   key: 'issue-file', type=1, flags=1, data=/etc/issue
12/12 15:32:05 dem   key: 'prompt-waittime', type=0, flags=1, data=500
12/12 15:32:05 dem   key: 'login-prompt', type=1, flags=1, data=@!login: 
12/12 15:32:05 dem   key: 'login-time', type=0, flags=1, data=30
12/12 15:32:05 dem   key: 'fido-send-emsi', type=3, flags=1, data=TRUE
12/12 15:32:05 dem   key: 'fax-id', type=1, flags=3, data=617-436-5141
12/12 15:32:05 dem   key: 'fax-min-speed', type=0, flags=1, data=0
12/12 15:32:05 dem   key: 'fax-max-speed', type=0, flags=1, data=14400
12/12 15:32:05 dem   key: 'fax-server-file', type=1, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'diskspace', type=0, flags=1, data=1024
12/12 15:32:05 dem   key: 'notify', type=0, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'fax-owner', type=1, flags=1, data=uucp
12/12 15:32:05 dem   key: 'fax-group', type=1, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'fax-mode', type=0, flags=1, data=432
12/12 15:32:05 dem   key: 'debug', type=0, flags=2, data=9
12/12 15:32:05 dem   key: 'statistics-chat', type=2, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'statistics-file', type=1, flags=0, data=(empty)
12/12 15:32:05 dem   key: 'gettydefs', type=1, flags=1, data=n
12/12 15:32:05 dem   key: 'term', type=1, flags=0, data=(empty)
12/12 15:32:05 dem  check for lockfiles
12/12 15:32:05 dem   checklock: no active process has lock, will remove
12/12 15:32:05 dem  locking the line
12/12 15:32:05 dem   makelock(modem) called
12/12 15:32:05 dem   do_makelock: lock='/var/lock/LCK..modem'
12/12 15:32:05 dem   lock made
12/12 15:32:06 dem   tio_get_rs232_lines: status: RTS CTS DSR DTR
12/12 15:32:06 dem  lowering DTR to reset Modem
12/12 15:32:06 dem   tss: set speed to 38400 (017)
12/12 15:32:06 dem   tio_set_flow_control( HARD )
12/12 15:32:06 dem   waiting for line to clear (VTIME), read: ER[0d][0a]
12/12 15:32:07 dem  send: ATS0=0Q0&D3&C1 OK[0d]
12/12 15:32:07 dem  mdm_send: 'ATI'
12/12 15:32:07 dem    got:ATS0=0Q0&D3&C1 OK[0d]
12/12 15:32:07 dem   mdm_gis: string 1: 'ATS0=0Q0&D3&C1 OK'
12/12 15:32:07 dem    got:[0d][0a]ERROR[0d]
12/12 15:32:07 dem   mdm_identify: string 'ATS0=0Q0&D3&C1 OK'
12/12 15:32:07 dem  non-numeric ID string: 'ATS0=0Q0&D3&C1 OK'
12/12 15:32:07 dem  mdm_send: 'AT+FCLASS=2.0'
12/12 15:32:07 dem    got:[0a]AT+FCLASS=2.0[0d]
12/12 15:32:07 dem   mdm_command: string 'AT+FCLASS=2.0'
12/12 15:32:07 dem    got:[0d][0a]ERROR[0d]
12/12 15:32:07 dem   mdm_command: string 'ERROR' -> ERROR
12/12 15:32:07 dem  mdm_send: 'AT+FCLASS=2'
12/12 15:32:07 dem    got:[0a]AT+FCLASS=2[0d]
12/12 15:32:07 dem   mdm_command: string 'AT+FCLASS=2'
12/12 15:32:07 dem    got:[0d][0a]OK[0d]
12/12 15:32:07 dem   mdm_command: string 'OK' -> OK
12/12 15:32:07 dem  mdm_send: 'AT+FCLASS=0'
12/12 15:32:07 dem    got:[0a]AT+FCLASS=0[0d]
12/12 15:32:07 dem   mdm_command: string 'AT+FCLASS=0'
12/12 15:32:07 dem    got:[0d][0a]OK[0d]
12/12 15:32:07 dem   mdm_command: string 'OK' -> OK
12/12 15:32:07 dem  mdm_send: 'AT+FAA=1;+FCR=1'
12/12 15:32:07 dem    got:[0a]AT+FAA=1;+FCR=1[0d]
12/12 15:32:07 dem   mdm_command: string 'AT+FAA=1;+FCR=1'
12/12 15:32:07 dem    got:[0d][0a]OK[0d]
12/12 15:32:07 dem   mdm_command: string 'OK' -> OK
12/12 15:32:07 dem  mdm_send: 'AT+FBOR=0'
12/12 15:32:07 dem    got:[0a]AT+FBOR=0[0d]
12/12 15:32:07 dem   mdm_command: string 'AT+FBOR=0'
12/12 15:32:07 dem    got:[0d][0a]OK[0d]
12/12 15:32:08 dem   mdm_command: string 'OK' -> OK
12/12 15:32:08 dem  mdm_send: 'AT+FLID="617-436-5141"'
12/12 15:32:08 dem    got:[0a]AT+FLID="617-436-5141"[0d]
12/12 15:32:08 dem   mdm_command: string 'AT+FLID="617-436-5141"'
12/12 15:32:08 dem    got:[0d][0a]OK[0d]
12/12 15:32:08 dem   mdm_command: string 'OK' -> OK
12/12 15:32:08 dem  mdm_send: 'AT+FDCC=1,5,0,2,0,0,0,0'
12/12 15:32:08 dem    got:[0a]AT+FDCC=1,5,0,2,0,0,0,0[0d]
12/12 15:32:08 dem   mdm_command: string 'AT+FDCC=1,5,0,2,0,0,0,0'
12/12 15:32:08 dem    got:[0d][0a]OK[0d]
12/12 15:32:08 dem   mdm_command: string 'OK' -> OK
12/12 15:32:08 dem   waiting for line to clear (VTIME), read: 
12/12 15:32:08 dem   removing lock file
12/12 15:32:08 dem  waiting...
12/12 16:32:08 dem    select returned 0