15:04:28.589477 ModemManager[3524]: logging: level 'DEBUG' 15:04:28.639102 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> disabling) 15:04:28.640230 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (0) 15:04:28.640251 ModemManager[3524]: Periodic signal quality checks disabled 15:04:28.640260 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (lte -> unknown) 15:04:28.640267 ModemManager[3524]: Periodic access technology checks disabled 15:04:28.641584 ModemManager[3524]: Modem has messaging capabilities, disabling the Messaging interface... 15:04:28.641602 ModemManager[3524]: (ttyUSB1) Removing messaging unsolicited events handlers 15:04:28.641621 ModemManager[3524]: (ttyUSB2) Removing messaging unsolicited events handlers 15:04:28.641628 ModemManager[3524]: Modem has location capabilities, disabling the Location interface... 15:04:28.641634 ModemManager[3524]: Need to disable the following location sources: '3gpp-lac-ci' 15:04:28.641641 ModemManager[3524]: Disabled location '3gpp-lac-ci' gathering... 15:04:28.641647 ModemManager[3524]: Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface... 15:04:28.641654 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:28.641660 ModemManager[3524]: (ttyUSB1) Removing unsolicited result code handlers 15:04:28.641667 ModemManager[3524]: (ttyUSB2) Removing unsolicited result code handlers 15:04:28.641673 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:28.642949 ModemManager[3524]: Modem has 3GPP capabilities, disabling the Modem 3GPP interface... 15:04:28.643040 ModemManager[3524]: Periodic 3GPP registration checks disabled 15:04:28.643117 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:28.643209 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=0' 15:04:28.656108 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:28.656247 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:28.656310 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:28.656361 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=0' 15:04:28.673068 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:28.673127 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:28.673195 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:28.673250 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=0' 15:04:28.687547 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:28.687643 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:28.687752 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:28.687835 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=0' 15:04:28.701982 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:28.702100 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:28.702582 ModemManager[3524]: (ttyUSB1) cleaning up unsolicited registration messages handlers 15:04:28.702620 ModemManager[3524]: (ttyUSB2) cleaning up unsolicited registration messages handlers 15:04:28.702669 ModemManager[3524]: (ttyUSB1) Removing 3GPP unsolicited events handlers 15:04:28.702725 ModemManager[3524]: (ttyUSB2) Removing 3GPP unsolicited events handlers 15:04:28.702846 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:28.702967 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=0' 15:04:28.714677 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:28.714782 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:28.714917 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:28.715006 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=0' 15:04:28.728919 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:28.729040 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:28.729147 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:28.729234 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=0' 15:04:28.740748 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:28.740826 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> unknown) 15:04:28.741101 ModemManager[3524]: Bearer not allowed to connect, not registered in 3GPP network 15:04:28.741210 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:28.741836 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:04:28.741909 ModemManager[3524]: (ttyUSB1) closing serial port... 15:04:28.753169 ModemManager[3524]: (ttyUSB1) serial port closed 15:04:28.753223 ModemManager[3524]: (ttyUSB2) device open count is 0 (close) 15:04:28.753236 ModemManager[3524]: (ttyUSB2) closing serial port... 15:04:28.759724 ModemManager[3524]: (ttyUSB2) serial port closed 15:04:28.759762 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabling -> disabled) 15:04:28.764029 ModemManager[3524]: (ttyUSB1) opening serial port... 15:04:28.794926 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:04:28.794976 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:04:28.795094 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:04:28.809917 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1OK' 15:04:28.810075 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:28.810182 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:28.810265 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=0' 15:04:28.832280 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1' 15:04:29.248782 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:29.248896 ModemManager[3524]: Modem set in low-power mode... 15:04:29.249061 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:04:29.249116 ModemManager[3524]: (ttyUSB1) closing serial port... 15:04:29.260515 ModemManager[3524]: (ttyUSB1) serial port closed 15:04:30.314755 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling) 15:04:30.315371 ModemManager[3524]: Skipping modem initialization: not first enabling 15:04:30.315425 ModemManager[3524]: (ttyUSB1) opening serial port... 15:04:30.346277 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:04:30.346324 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:04:30.346337 ModemManager[3524]: (ttyUSB1): running init sequence... 15:04:30.346345 ModemManager[3524]: (ttyUSB2) opening serial port... 15:04:30.363656 ModemManager[3524]: (ttyUSB2): port attributes not fully set 15:04:30.363701 ModemManager[3524]: (ttyUSB2) device open count is 1 (open) 15:04:30.363714 ModemManager[3524]: (ttyUSB2): running init sequence... 15:04:30.363722 ModemManager[3524]: Flashing primary AT port before enabling... 15:04:30.374360 ModemManager[3524]: (ttyUSB1): --> 'ATE0' 15:04:30.374407 ModemManager[3524]: (ttyUSB2): --> 'ATE0' 15:04:30.382796 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.383006 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:30.383130 ModemManager[3524]: (ttyUSB1): --> 'ATV1' 15:04:30.389659 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.389732 ModemManager[3524]: (ttyUSB1): --> 'AT+CMEE=1' 15:04:30.403847 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.403947 ModemManager[3524]: (ttyUSB1): --> 'ATX4' 15:04:30.410515 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.410700 ModemManager[3524]: (ttyUSB1): --> 'AT&C1' 15:04:30.417793 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.485194 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.485244 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:04:30.500138 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1OK' 15:04:30.500253 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.500269 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:30.500276 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=1' 15:04:30.550764 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1' 15:04:30.551040 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.551064 ModemManager[3524]: Modem set in full-power mode... 15:04:30.551169 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.551642 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.551657 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.551663 ModemManager[3524]: (ttyUSB1): --> 'AT+IFC=1,1' 15:04:30.623359 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.623778 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:30.623810 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.623840 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:30.623849 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS="UCS2"' 15:04:30.646172 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.646357 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.646377 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:30.646383 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS?' 15:04:30.656616 ModemManager[3524]: (ttyUSB1): <-- '+CSCS: "UCS2"OK' 15:04:30.656717 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.656803 ModemManager[3524]: Modem has 3GPP capabilities, enabling the Modem 3GPP interface... 15:04:30.656813 ModemManager[3524]: (ttyUSB1) Setting 3GPP unsolicited events handlers 15:04:30.656819 ModemManager[3524]: (ttyUSB2) Setting 3GPP unsolicited events handlers 15:04:30.657020 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.657030 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=3,0,0,1' 15:04:30.679753 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.679775 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:30.679788 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.679793 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=3,0,0,1' 15:04:30.708314 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:04:30.708515 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:30.708615 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.708726 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:30.708736 ModemManager[3524]: (ttyUSB1): --> 'AT^PORTSEL=0' 15:04:30.735916 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:04:30.797758 ModemManager[3524]: (ttyUSB1): <-- 'ERROR' 15:04:30.797844 ModemManager[3524]: Got failure code 100: Unknown error 15:04:30.797945 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=1' 15:04:30.807049 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.807309 ModemManager[3524]: (ttyUSB1) setting up 3GPP unsolicited registration messages handlers 15:04:30.807399 ModemManager[3524]: (ttyUSB2) setting up 3GPP unsolicited registration messages handlers 15:04:30.807409 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.807543 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.807553 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=2' 15:04:30.822238 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.822260 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:30.822278 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.822285 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=2' 15:04:30.834257 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:30.834286 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.834293 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:30.834298 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=2' 15:04:30.846532 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:30.846566 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:30.846571 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.846576 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=2' 15:04:30.858537 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:30.858562 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:30.858570 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:04:30.858575 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.858597 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:04:30.868751 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:04:30.868779 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> registering) 15:04:30.868786 ModemManager[3524]: loading Operator Code... 15:04:30.868792 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.868797 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:04:30.868802 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:04:30.868807 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:04:30.890586 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:30.890611 ModemManager[3524]: loading Operator Name... 15:04:30.890768 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:04:30.890777 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:04:30.890782 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:04:30.902933 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:04:30.902988 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> searching) 15:04:30.903192 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:30.903648 ModemManager[3524]: Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... 15:04:30.903662 ModemManager[3524]: (ttyUSB1) Setting unsolicited result code handlers 15:04:30.903668 ModemManager[3524]: (ttyUSB2) Setting unsolicited result code handlers 15:04:30.903673 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.903678 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:04:30.927438 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:30.927463 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:30.927470 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:04:30.927476 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:30.927481 ModemManager[3524]: Modem has location capabilities, enabling the Location interface... 15:04:30.927487 ModemManager[3524]: Need to enable the following location sources: '3gpp-lac-ci' 15:04:30.927492 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:04:30.927496 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:30.927501 ModemManager[3524]: loading Operator Code... 15:04:30.927506 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:30.927510 ModemManager[3524]: Modem has messaging capabilities, enabling the Messaging interface... 15:04:30.928600 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:04:30.928616 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:04:30.938555 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:04:30.938728 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> searching) 15:04:30.938821 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:04:30.938831 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:04:30.939145 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:04:30.961794 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:30.961948 ModemManager[3524]: loading Operator Name... 15:04:30.962007 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:04:30.962059 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:04:30.962121 ModemManager[3524]: Successfully set preferred SMS mode: 'PDU' 15:04:30.962214 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:04:30.962264 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:04:30.962316 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:04:30.973428 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:04:30.973535 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:04:30.973608 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:04:31.008467 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:31.008628 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:31.008675 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="","SM","SM"' 15:04:31.047839 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",65,60,136,18+CIEV: 1,5^SRVST:1^HCSQ: "LTE",65,60,136,18^MODE:5,4' 15:04:31.047926 ModemManager[3524]: Access Technology: 'umts' 15:04:31.048037 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (unknown -> umts) 15:04:31.080884 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:04:31.080987 ModemManager[3524]: Listing SMS parts in storage 'sm' 15:04:31.081035 ModemManager[3524]: Locking SMS storages to: mem1 (SM), mem2 (none)... 15:04:31.081094 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:31.081154 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:31.081207 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="SM"' 15:04:31.121517 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:04:31.121536 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:31.121542 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:31.121548 ModemManager[3524]: (ttyUSB1): --> 'AT+CMGL=4' 15:04:31.136380 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:31.136406 ModemManager[3524]: (ttyUSB1) Setting messaging unsolicited events handlers 15:04:31.136413 ModemManager[3524]: (ttyUSB2) Setting messaging unsolicited events handlers 15:04:31.136418 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:31.136423 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:31.136428 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0' 15:04:31.157071 ModemManager[3524]: (ttyUSB1): <-- '+CMS ERROR: 303' 15:04:31.157098 ModemManager[3524]: Got failure code 303: Operation not supported 15:04:31.157115 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0' 15:04:31.180517 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:31.181657 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:31.181689 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> searching) 15:04:31.630924 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 3,1' 15:04:31.630965 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:2' 15:04:31.631172 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",65,60,136,18' 15:04:31.631589 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 2,1' 15:04:31.631616 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:04:31.632016 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 6,1+CREG: 1,"FFFE","1", 7+CGREG: 1,"FFFE","1",7^SRVST:2' 15:04:31.632126 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (searching -> registering) 15:04:31.632162 ModemManager[3524]: loading Operator Code... 15:04:31.632210 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:31.632823 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:04:31.685991 ModemManager[3524]: (ttyUSB1): <-- '^NWTIME: 13/09/03,22:04:33-28,01' 15:04:31.686806 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,2,"310410",7OK' 15:04:31.687261 ModemManager[3524]: loading Operator Name... 15:04:31.687301 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:31.687313 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:31.687766 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:04:31.757058 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,0,"AT&T",7OK' 15:04:31.757315 ModemManager[3524]: loaded Operator Name: AT&T 15:04:31.757476 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:31.759232 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:04:31.759424 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (searching -> registered) 15:04:31.760341 ModemManager[3524]: Periodic signal quality checks enabled (interval = 3s) 15:04:31.760412 ModemManager[3524]: loading signal quality... 15:04:31.760475 ModemManager[3524]: loading signal quality... 15:04:31.760552 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:31.760631 ModemManager[3524]: Periodic access technology checks enabled 15:04:31.760683 ModemManager[3524]: loading access technology (huawei)... 15:04:31.760751 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:31.761275 ModemManager[3524]: (ttyUSB1): --> 'AT+CIND?' 15:04:31.778348 ModemManager[3524]: (ttyUSB1): <-- '+CIND: ,5,1,1,0,0,1,0OK' 15:04:31.778492 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:31.778618 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:04:31.778658 ModemManager[3524]: Periodic signal quality checks rescheduled (interval = 30s) 15:04:31.778712 ModemManager[3524]: (ttyUSB1): --> 'AT^SYSINFOEX' 15:04:31.793311 ModemManager[3524]: (ttyUSB1): <-- '^SYSINFOEX: 2,3,0,1,,6,"LTE",101,"LTE"OK' 15:04:31.793451 ModemManager[3524]: Access Technology: 'lte' 15:04:31.793536 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (umts -> lte) 15:04:31.793558 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:42.455434 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> disabling) 15:04:42.456959 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (0) 15:04:42.456996 ModemManager[3524]: Periodic signal quality checks disabled 15:04:42.457007 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (lte -> unknown) 15:04:42.457017 ModemManager[3524]: Periodic access technology checks disabled 15:04:42.457058 ModemManager[3524]: Modem has messaging capabilities, disabling the Messaging interface... 15:04:42.457066 ModemManager[3524]: (ttyUSB1) Removing messaging unsolicited events handlers 15:04:42.457074 ModemManager[3524]: (ttyUSB2) Removing messaging unsolicited events handlers 15:04:42.458294 ModemManager[3524]: Modem has location capabilities, disabling the Location interface... 15:04:42.458319 ModemManager[3524]: Need to disable the following location sources: '3gpp-lac-ci' 15:04:42.458330 ModemManager[3524]: Disabled location '3gpp-lac-ci' gathering... 15:04:42.458338 ModemManager[3524]: Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface... 15:04:42.458346 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:42.458354 ModemManager[3524]: (ttyUSB1) Removing unsolicited result code handlers 15:04:42.458362 ModemManager[3524]: (ttyUSB2) Removing unsolicited result code handlers 15:04:42.458369 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:42.458377 ModemManager[3524]: Modem has 3GPP capabilities, disabling the Modem 3GPP interface... 15:04:42.458384 ModemManager[3524]: Periodic 3GPP registration checks disabled 15:04:42.458392 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:42.462055 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=0' 15:04:42.474006 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:42.474161 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:42.474222 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:42.474274 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=0' 15:04:42.495925 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:42.495943 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:42.495949 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:42.495954 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=0' 15:04:42.509855 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:42.510099 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:42.510146 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:42.510156 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=0' 15:04:42.524261 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:42.524307 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:42.524735 ModemManager[3524]: (ttyUSB1) cleaning up unsolicited registration messages handlers 15:04:42.524773 ModemManager[3524]: (ttyUSB2) cleaning up unsolicited registration messages handlers 15:04:42.524830 ModemManager[3524]: (ttyUSB1) Removing 3GPP unsolicited events handlers 15:04:42.524934 ModemManager[3524]: (ttyUSB2) Removing 3GPP unsolicited events handlers 15:04:42.525059 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:42.525167 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=0' 15:04:42.537046 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:42.537122 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:42.537218 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:42.537302 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=0' 15:04:42.551294 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:42.551363 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:42.551463 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:42.551548 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=0' 15:04:42.565174 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:42.565242 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> unknown) 15:04:42.565499 ModemManager[3524]: Bearer not allowed to connect, not registered in 3GPP network 15:04:42.565616 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:42.566180 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:04:42.566250 ModemManager[3524]: (ttyUSB1) closing serial port... 15:04:42.577202 ModemManager[3524]: (ttyUSB1) serial port closed 15:04:42.577239 ModemManager[3524]: (ttyUSB2) device open count is 0 (close) 15:04:42.577251 ModemManager[3524]: (ttyUSB2) closing serial port... 15:04:42.583724 ModemManager[3524]: (ttyUSB2) serial port closed 15:04:42.583762 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabling -> disabled) 15:04:42.588042 ModemManager[3524]: (ttyUSB1) opening serial port... 15:04:42.618825 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:04:42.618888 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:04:42.618967 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:04:42.634175 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1OK' 15:04:42.634285 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:42.634390 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:42.634474 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=0' 15:04:42.670669 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1' 15:04:43.130687 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:43.130948 ModemManager[3524]: Modem set in low-power mode... 15:04:43.130973 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:04:43.130982 ModemManager[3524]: (ttyUSB1) closing serial port... 15:04:43.142175 ModemManager[3524]: (ttyUSB1) serial port closed 15:04:44.527321 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling) 15:04:44.528121 ModemManager[3524]: Skipping modem initialization: not first enabling 15:04:44.528140 ModemManager[3524]: (ttyUSB1) opening serial port... 15:04:44.558948 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:04:44.558998 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:04:44.559309 ModemManager[3524]: (ttyUSB1): running init sequence... 15:04:44.559328 ModemManager[3524]: (ttyUSB2) opening serial port... 15:04:44.576465 ModemManager[3524]: (ttyUSB2): port attributes not fully set 15:04:44.576518 ModemManager[3524]: (ttyUSB2) device open count is 1 (open) 15:04:44.576529 ModemManager[3524]: (ttyUSB2): running init sequence... 15:04:44.576538 ModemManager[3524]: Flashing primary AT port before enabling... 15:04:44.586908 ModemManager[3524]: (ttyUSB1): --> 'ATE0' 15:04:44.586951 ModemManager[3524]: (ttyUSB2): --> 'ATE0' 15:04:44.592858 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.592929 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:44.593017 ModemManager[3524]: (ttyUSB1): --> 'ATV1' 15:04:44.599909 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.600047 ModemManager[3524]: (ttyUSB1): --> 'AT+CMEE=1' 15:04:44.613804 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.613853 ModemManager[3524]: (ttyUSB1): --> 'ATX4' 15:04:44.620666 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.620704 ModemManager[3524]: (ttyUSB1): --> 'AT&C1' 15:04:44.627660 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.697086 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:44.697135 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:04:44.712281 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1OK' 15:04:44.712404 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:44.712516 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:44.712602 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=1' 15:04:44.763909 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1' 15:04:44.764067 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.764217 ModemManager[3524]: Modem set in full-power mode... 15:04:44.764349 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:44.764846 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:44.765015 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:44.765112 ModemManager[3524]: (ttyUSB1): --> 'AT+IFC=1,1' 15:04:44.836561 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.836610 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:44.836805 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:44.836957 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:44.837045 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS="UCS2"' 15:04:44.854288 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.854357 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:44.854458 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:44.854541 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS?' 15:04:44.869683 ModemManager[3524]: (ttyUSB1): <-- '+CSCS: "UCS2"OK' 15:04:44.869786 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:44.869931 ModemManager[3524]: Modem has 3GPP capabilities, enabling the Modem 3GPP interface... 15:04:44.870047 ModemManager[3524]: (ttyUSB1) Setting 3GPP unsolicited events handlers 15:04:44.870100 ModemManager[3524]: (ttyUSB2) Setting 3GPP unsolicited events handlers 15:04:44.870238 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:44.870346 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=3,0,0,1' 15:04:44.894161 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:44.894259 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:44.894365 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:44.894456 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=3,0,0,1' 15:04:44.922578 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:04:44.922776 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:44.922991 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:44.923093 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:44.923185 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:04:44.923291 ModemManager[3524]: (ttyUSB1): --> 'AT^PORTSEL=0' 15:04:45.005436 ModemManager[3524]: (ttyUSB1): <-- 'ERROR' 15:04:45.005484 ModemManager[3524]: Got failure code 100: Unknown error 15:04:45.005555 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=1' 15:04:45.017323 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:45.017741 ModemManager[3524]: (ttyUSB1) setting up 3GPP unsolicited registration messages handlers 15:04:45.017778 ModemManager[3524]: (ttyUSB2) setting up 3GPP unsolicited registration messages handlers 15:04:45.017789 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.017894 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:45.018019 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=2' 15:04:45.029579 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:45.029678 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:45.029786 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.029894 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=2' 15:04:45.041345 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:45.041437 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:45.041541 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:45.041628 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=2' 15:04:45.054280 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:45.054360 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:04:45.054463 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.054546 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=2' 15:04:45.067158 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:04:45.067196 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:04:45.067272 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:04:45.067370 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:45.067474 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:04:45.077786 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:04:45.077823 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> registering) 15:04:45.077836 ModemManager[3524]: loading Operator Code... 15:04:45.077922 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:45.078111 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:04:45.078189 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:04:45.078276 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:04:45.100960 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:45.101139 ModemManager[3524]: loading Operator Name... 15:04:45.101231 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:04:45.101315 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:04:45.101408 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:04:45.112909 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:04:45.112946 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> searching) 15:04:45.113227 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:45.113751 ModemManager[3524]: Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... 15:04:45.113862 ModemManager[3524]: (ttyUSB1) Setting unsolicited result code handlers 15:04:45.113952 ModemManager[3524]: (ttyUSB2) Setting unsolicited result code handlers 15:04:45.114064 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:45.114160 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:04:45.136892 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:45.137116 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:45.137226 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:04:45.137850 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.138358 ModemManager[3524]: Modem has location capabilities, enabling the Location interface... 15:04:45.138564 ModemManager[3524]: Need to enable the following location sources: '3gpp-lac-ci' 15:04:45.138838 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:04:45.139007 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:45.139207 ModemManager[3524]: loading Operator Code... 15:04:45.139336 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:45.139962 ModemManager[3524]: Modem has messaging capabilities, enabling the Messaging interface... 15:04:45.140107 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:04:45.140204 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:04:45.174137 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:04:45.174224 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> searching) 15:04:45.174599 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:04:45.174651 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:04:45.175251 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:04:45.212543 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",65,60,157,18+CIEV: 1,5^SRVST:1^HCSQ: "LTE",65,60,157,18^MODE:5,4^SRVST:1^HCSQ: "LTE",65,60,157,18' 15:04:45.212686 ModemManager[3524]: Access Technology: 'umts' 15:04:45.212792 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (unknown -> umts) 15:04:45.246316 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:45.246487 ModemManager[3524]: loading Operator Name... 15:04:45.246579 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:04:45.246666 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:04:45.246767 ModemManager[3524]: Successfully set preferred SMS mode: 'PDU' 15:04:45.246964 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:04:45.247047 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:04:45.247132 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:04:45.300813 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:04:45.300993 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:04:45.301117 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:04:45.331203 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:04:45.331383 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:45.331505 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="","SM","SM"' 15:04:45.358302 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:04:45.358396 ModemManager[3524]: Listing SMS parts in storage 'sm' 15:04:45.358468 ModemManager[3524]: Locking SMS storages to: mem1 (SM), mem2 (none)... 15:04:45.358556 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:45.358649 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:45.358733 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="SM"' 15:04:45.376192 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:04:45.376309 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:45.376424 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:45.376510 ModemManager[3524]: (ttyUSB1): --> 'AT+CMGL=4' 15:04:45.391068 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:45.391223 ModemManager[3524]: (ttyUSB1) Setting messaging unsolicited events handlers 15:04:45.391293 ModemManager[3524]: (ttyUSB2) Setting messaging unsolicited events handlers 15:04:45.391371 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.391495 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:45.391603 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0' 15:04:45.412675 ModemManager[3524]: (ttyUSB1): <-- '+CMS ERROR: 303' 15:04:45.412755 ModemManager[3524]: Got failure code 303: Operation not supported 15:04:45.412896 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0' 15:04:45.435104 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:45.435177 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.435308 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> searching) 15:04:45.877648 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 3,1' 15:04:45.877938 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:2' 15:04:45.877962 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",65,60,157,18' 15:04:45.878452 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 2,1' 15:04:45.879561 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:04:45.879596 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 6,1' 15:04:45.879607 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 1,"FFFE","4BCD80F", 7+CGREG: 1,"FFFE","4BCD80F",7^SRVST:2' 15:04:45.879616 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (searching -> registering) 15:04:45.879625 ModemManager[3524]: loading Operator Code... 15:04:45.879632 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:45.879640 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:04:45.931680 ModemManager[3524]: (ttyUSB1): <-- '^NWTIME: 13/09/03,22:04:47-28,01' 15:04:45.932533 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,2,"310410",7OK' 15:04:45.932690 ModemManager[3524]: loading Operator Name... 15:04:45.932780 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:45.932887 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:45.932994 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:04:45.998619 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,0,"AT&T",7OK' 15:04:45.998861 ModemManager[3524]: loaded Operator Name: AT&T 15:04:45.999034 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:45.999510 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:04:46.000169 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (searching -> registered) 15:04:46.001358 ModemManager[3524]: Periodic signal quality checks enabled (interval = 3s) 15:04:46.001593 ModemManager[3524]: loading signal quality... 15:04:46.001801 ModemManager[3524]: loading signal quality... 15:04:46.002043 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:46.002272 ModemManager[3524]: Periodic access technology checks enabled 15:04:46.002297 ModemManager[3524]: loading access technology (huawei)... 15:04:46.002336 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:46.004076 ModemManager[3524]: (ttyUSB1): --> 'AT+CIND?' 15:04:46.020474 ModemManager[3524]: (ttyUSB1): <-- '+CIND: ,5,1,1,0,0,1,0OK' 15:04:46.020602 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:46.020714 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:04:46.020731 ModemManager[3524]: Periodic signal quality checks rescheduled (interval = 30s) 15:04:46.020766 ModemManager[3524]: (ttyUSB1): --> 'AT^SYSINFOEX' 15:04:46.035460 ModemManager[3524]: (ttyUSB1): <-- '^SYSINFOEX: 2,3,0,1,,6,"LTE",101,"LTE"OK' 15:04:46.035593 ModemManager[3524]: Access Technology: 'lte' 15:04:46.035677 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (umts -> lte) 15:04:46.035698 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:46.622783 ModemManager[3524]: Simple connect started... 15:04:46.622816 ModemManager[3524]: PIN: unspecified 15:04:46.622826 ModemManager[3524]: Operator ID: unspecified 15:04:46.622834 ModemManager[3524]: Allowed roaming: no 15:04:46.622841 ModemManager[3524]: APN: pta 15:04:46.622849 ModemManager[3524]: IP family: unspecified 15:04:46.622856 ModemManager[3524]: Allowed authentication: unspecified 15:04:46.622892 ModemManager[3524]: User: unspecified 15:04:46.622903 ModemManager[3524]: Password: unspecified 15:04:46.622910 ModemManager[3524]: Number: *99# 15:04:46.622918 ModemManager[3524]: Simple connect state (4/8): Wait to get fully enabled 15:04:46.624179 ModemManager[3524]: Simple connect state (5/8): Register 15:04:46.624194 ModemManager[3524]: Launching automatic network registration... 15:04:46.624201 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:46.624205 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=0' 15:04:46.665317 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:46.665341 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:04:46.665348 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:46.665353 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:46.665358 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:04:46.675713 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,1,"FFFE","4BCD80F",7OK' 15:04:46.676030 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP location updated (MCC: '0', MNC: '0', Location area code: 'FFFE', Cell ID: '4BCD80F') 15:04:46.676065 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:46.676075 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:46.676083 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:04:46.688909 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,1,"FFFE","4BCD80F",7OK' 15:04:46.689089 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:46.689195 ModemManager[3524]: Modem is currently registered in a 3GPP network 15:04:46.689305 ModemManager[3524]: Simple connect state (6/8): Bearer 15:04:46.689368 ModemManager[3524]: Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/1'... 15:04:46.689412 ModemManager[3524]: Simple connect state (7/8): Connect 15:04:46.689481 ModemManager[3524]: Connecting bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:04:46.689581 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting) 15:04:46.690651 ModemManager[3524]: Launching 3GPP connection attempt with APN 'pta' 15:04:46.690746 ModemManager[3524]: No specific IP family requested, defaulting to ipv4 15:04:46.690906 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:46.691208 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISDUP=1,1,"pta","","",0' 15:04:46.727905 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:46.728287 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:46.728326 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:46.728646 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISSTATQRY?' 15:04:46.747057 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTATQRY: 0,33,,"IPV4"OK' 15:04:46.747429 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:46.768285 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTAT: 1,,,"IPV4"' 15:04:47.992222 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:47.992262 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISSTATQRY?' 15:04:48.012484 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTATQRY: 1,,,"IPV4"OK' 15:04:48.013921 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:48.013954 ModemManager[3524]: (usb0): port now connected 15:04:48.013964 ModemManager[3524]: Connected bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:04:48.013972 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> connected) 15:04:48.013980 ModemManager[3524]: Simple connect state (8/8): All done 15:04:48.208226 ModemManager[3524]: Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:04:48.208261 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connected -> disconnecting) 15:04:48.208944 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:48.208957 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISDUP=1,0' 15:04:48.226670 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:48.226686 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:48.226693 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:48.226698 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISSTATQRY?' 15:04:48.252261 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTAT: 0,33,,"IPV4"' 15:04:48.267180 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTATQRY: 0,33,,"IPV4"OK' 15:04:48.267846 ModemManager[3524]: (usb0): port now disconnected 15:04:48.267899 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:48.267912 ModemManager[3524]: Disconnected bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:04:48.267987 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disconnecting -> registered) 15:04:58.384001 ModemManager[3524]: Simple connect started... 15:04:58.384042 ModemManager[3524]: PIN: unspecified 15:04:58.384053 ModemManager[3524]: Operator ID: unspecified 15:04:58.384061 ModemManager[3524]: Allowed roaming: no 15:04:58.384069 ModemManager[3524]: APN: pta 15:04:58.384077 ModemManager[3524]: IP family: unspecified 15:04:58.384084 ModemManager[3524]: Allowed authentication: unspecified 15:04:58.384092 ModemManager[3524]: User: unspecified 15:04:58.384100 ModemManager[3524]: Password: unspecified 15:04:58.384107 ModemManager[3524]: Number: *99# 15:04:58.384115 ModemManager[3524]: Simple connect state (4/8): Wait to get fully enabled 15:04:58.384453 ModemManager[3524]: Simple connect state (5/8): Register 15:04:58.384471 ModemManager[3524]: Launching automatic network registration... 15:04:58.384479 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:58.384487 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=0' 15:04:58.418655 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:58.418765 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:04:58.418776 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:58.418925 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:58.418935 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:04:58.431644 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,1,"FFFE","4BCD80F",7OK' 15:04:58.431860 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:58.431890 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:58.431897 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:04:58.446400 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,1,"FFFE","4BCD80F",7OK' 15:04:58.447810 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:58.447844 ModemManager[3524]: Modem is currently registered in a 3GPP network 15:04:58.447854 ModemManager[3524]: Simple connect state (6/8): Bearer 15:04:58.447862 ModemManager[3524]: Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/1'... 15:04:58.447886 ModemManager[3524]: Simple connect state (7/8): Connect 15:04:58.447895 ModemManager[3524]: Connecting bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:04:58.447903 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting) 15:04:58.447911 ModemManager[3524]: Launching 3GPP connection attempt with APN 'pta' 15:04:58.447918 ModemManager[3524]: No specific IP family requested, defaulting to ipv4 15:04:58.447926 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:58.447933 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISDUP=1,1,"pta","","",0' 15:04:58.486898 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:04:58.487022 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:04:58.487124 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:04:58.487213 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISSTATQRY?' 15:04:58.505825 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTATQRY: 0,33,,"IPV4"OK' 15:04:58.505984 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:04:58.525717 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTAT: 1,,,"IPV4"' 15:04:59.992080 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:04:59.992218 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISSTATQRY?' 15:05:00.012263 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTATQRY: 1,,,"IPV4"OK' 15:05:00.012971 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.013006 ModemManager[3524]: (usb0): port now connected 15:05:00.013016 ModemManager[3524]: Connected bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:05:00.013024 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> connected) 15:05:00.013524 ModemManager[3524]: Simple connect state (8/8): All done 15:05:00.217490 ModemManager[3524]: Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:05:00.217523 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connected -> disconnecting) 15:05:00.217534 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:00.217543 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISDUP=1,0' 15:05:00.234565 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:00.234584 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:00.234590 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:00.234595 ModemManager[3524]: (ttyUSB1): --> 'AT^NDISSTATQRY?' 15:05:00.259756 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTAT: 0,33,,"IPV4"' 15:05:00.273265 ModemManager[3524]: (ttyUSB1): <-- '^NDISSTATQRY: 0,33,,"IPV4"OK' 15:05:00.274496 ModemManager[3524]: (usb0): port now disconnected 15:05:00.274519 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.274526 ModemManager[3524]: Disconnected bearer '/org/freedesktop/ModemManager1/Bearer/1' 15:05:00.274533 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disconnecting -> registered) 15:05:00.280877 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> disabling) 15:05:00.281834 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (0) 15:05:00.281855 ModemManager[3524]: Periodic signal quality checks disabled 15:05:00.281862 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (lte -> unknown) 15:05:00.281880 ModemManager[3524]: Periodic access technology checks disabled 15:05:00.281905 ModemManager[3524]: Modem has messaging capabilities, disabling the Messaging interface... 15:05:00.281911 ModemManager[3524]: (ttyUSB1) Removing messaging unsolicited events handlers 15:05:00.281915 ModemManager[3524]: (ttyUSB2) Removing messaging unsolicited events handlers 15:05:00.282674 ModemManager[3524]: Modem has location capabilities, disabling the Location interface... 15:05:00.282690 ModemManager[3524]: Need to disable the following location sources: '3gpp-lac-ci' 15:05:00.282695 ModemManager[3524]: Disabled location '3gpp-lac-ci' gathering... 15:05:00.282700 ModemManager[3524]: Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface... 15:05:00.282705 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:00.282710 ModemManager[3524]: (ttyUSB1) Removing unsolicited result code handlers 15:05:00.282715 ModemManager[3524]: (ttyUSB2) Removing unsolicited result code handlers 15:05:00.282719 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.282724 ModemManager[3524]: Modem has 3GPP capabilities, disabling the Modem 3GPP interface... 15:05:00.282728 ModemManager[3524]: Periodic 3GPP registration checks disabled 15:05:00.282732 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:00.282780 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=0' 15:05:00.296279 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:00.296420 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:00.296482 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.296535 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=0' 15:05:00.307152 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:00.307168 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:00.307175 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:00.307180 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=0' 15:05:00.319239 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:00.319399 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:00.319419 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.319426 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=0' 15:05:00.331572 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:00.331617 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:00.332026 ModemManager[3524]: (ttyUSB1) cleaning up unsolicited registration messages handlers 15:05:00.332054 ModemManager[3524]: (ttyUSB2) cleaning up unsolicited registration messages handlers 15:05:00.332359 ModemManager[3524]: (ttyUSB1) Removing 3GPP unsolicited events handlers 15:05:00.332390 ModemManager[3524]: (ttyUSB2) Removing 3GPP unsolicited events handlers 15:05:00.332401 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:00.332409 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=0' 15:05:00.345992 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:00.346092 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:00.346194 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:00.346282 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=0' 15:05:00.357647 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:00.357717 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:00.357815 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.357936 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=0' 15:05:00.369303 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:00.369374 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> unknown) 15:05:00.369632 ModemManager[3524]: Bearer not allowed to connect, not registered in 3GPP network 15:05:00.369749 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:00.370519 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:05:00.370592 ModemManager[3524]: (ttyUSB1) closing serial port... 15:05:00.381613 ModemManager[3524]: (ttyUSB1) serial port closed 15:05:00.381651 ModemManager[3524]: (ttyUSB2) device open count is 0 (close) 15:05:00.381664 ModemManager[3524]: (ttyUSB2) closing serial port... 15:05:00.387891 ModemManager[3524]: (ttyUSB2) serial port closed 15:05:00.387957 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabling -> disabled) 15:05:00.391920 ModemManager[3524]: (ttyUSB1) opening serial port... 15:05:00.422710 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:05:00.422748 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:05:00.422816 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:05:00.437556 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1OK' 15:05:00.437669 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:00.437775 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:00.437858 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=0' 15:05:00.475050 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1' 15:05:00.776331 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:00.776380 ModemManager[3524]: Modem set in low-power mode... 15:05:00.776486 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:05:00.776553 ModemManager[3524]: (ttyUSB1) closing serial port... 15:05:00.788075 ModemManager[3524]: (ttyUSB1) serial port closed 15:05:01.934363 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling) 15:05:01.935055 ModemManager[3524]: Skipping modem initialization: not first enabling 15:05:01.935071 ModemManager[3524]: (ttyUSB1) opening serial port... 15:05:01.965989 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:05:01.966039 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:05:01.966050 ModemManager[3524]: (ttyUSB1): running init sequence... 15:05:01.966453 ModemManager[3524]: (ttyUSB2) opening serial port... 15:05:01.983348 ModemManager[3524]: (ttyUSB2): port attributes not fully set 15:05:01.983399 ModemManager[3524]: (ttyUSB2) device open count is 1 (open) 15:05:01.983409 ModemManager[3524]: (ttyUSB2): running init sequence... 15:05:01.983418 ModemManager[3524]: Flashing primary AT port before enabling... 15:05:01.993692 ModemManager[3524]: (ttyUSB1): --> 'ATE0' 15:05:01.993740 ModemManager[3524]: (ttyUSB2): --> 'ATE0' 15:05:01.999521 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:01.999612 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:01.999750 ModemManager[3524]: (ttyUSB1): --> 'ATV1' 15:05:02.006329 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.006376 ModemManager[3524]: (ttyUSB1): --> 'AT+CMEE=1' 15:05:02.019934 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.020027 ModemManager[3524]: (ttyUSB1): --> 'ATX4' 15:05:02.026292 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.026329 ModemManager[3524]: (ttyUSB1): --> 'AT&C1' 15:05:02.033338 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.104712 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.104762 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:05:02.122443 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1OK' 15:05:02.122571 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.122685 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.122776 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=1' 15:05:02.252788 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1' 15:05:02.252939 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.253090 ModemManager[3524]: Modem set in full-power mode... 15:05:02.253222 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.253716 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.254720 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.254725 ModemManager[3524]: (ttyUSB1): --> 'AT+IFC=1,1' 15:05:02.274070 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.274117 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.274295 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.274397 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.274478 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS="UCS2"' 15:05:02.403187 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.403289 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.403401 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.403486 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS?' 15:05:02.416162 ModemManager[3524]: (ttyUSB1): <-- '+CSCS: "UCS2"OK' 15:05:02.416549 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.416589 ModemManager[3524]: Modem has 3GPP capabilities, enabling the Modem 3GPP interface... 15:05:02.416966 ModemManager[3524]: (ttyUSB1) Setting 3GPP unsolicited events handlers 15:05:02.417006 ModemManager[3524]: (ttyUSB2) Setting 3GPP unsolicited events handlers 15:05:02.417357 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.417397 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=3,0,0,1' 15:05:02.437693 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.437790 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:02.437941 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.438032 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=3,0,0,1' 15:05:02.460564 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:02.460666 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.460780 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:02.460896 ModemManager[3524]: (ttyUSB1): --> 'AT^PORTSEL=0' 15:05:02.488940 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:05:02.489368 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:05:02.573821 ModemManager[3524]: (ttyUSB1): <-- 'ERROR' 15:05:02.573907 ModemManager[3524]: Got failure code 100: Unknown error 15:05:02.574391 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=1' 15:05:02.585665 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.586320 ModemManager[3524]: (ttyUSB1) setting up 3GPP unsolicited registration messages handlers 15:05:02.586362 ModemManager[3524]: (ttyUSB2) setting up 3GPP unsolicited registration messages handlers 15:05:02.586373 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.586897 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.586933 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=2' 15:05:02.598287 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.598636 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:02.598676 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.599047 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=2' 15:05:02.610417 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:02.610732 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.610772 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:02.611152 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=2' 15:05:02.623709 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.624135 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:02.624175 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.624512 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=2' 15:05:02.637045 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:02.637085 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:02.637527 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:05:02.637567 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.637899 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:05:02.648248 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:05:02.648383 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> registering) 15:05:02.648453 ModemManager[3524]: loading Operator Code... 15:05:02.648526 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.648696 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:02.648774 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:02.648861 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:05:02.672306 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:02.672482 ModemManager[3524]: loading Operator Name... 15:05:02.672574 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:02.672659 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:02.672752 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:05:02.684335 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:05:02.684383 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> searching) 15:05:02.684669 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.685256 ModemManager[3524]: Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... 15:05:02.687654 ModemManager[3524]: (ttyUSB1) Setting unsolicited result code handlers 15:05:02.687707 ModemManager[3524]: (ttyUSB2) Setting unsolicited result code handlers 15:05:02.687833 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.687948 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:05:02.709915 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:02.710108 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.710241 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:05:02.711042 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.712050 ModemManager[3524]: Modem has location capabilities, enabling the Location interface... 15:05:02.713033 ModemManager[3524]: Need to enable the following location sources: '3gpp-lac-ci' 15:05:02.713196 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:05:02.713279 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.713366 ModemManager[3524]: loading Operator Code... 15:05:02.713429 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.713966 ModemManager[3524]: Modem has messaging capabilities, enabling the Messaging interface... 15:05:02.714409 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:02.714473 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:05:02.724780 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:05:02.724806 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> searching) 15:05:02.725437 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:05:02.725476 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:05:02.725959 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:05:02.765822 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:02.766276 ModemManager[3524]: loading Operator Name... 15:05:02.766320 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:05:02.766370 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:05:02.766439 ModemManager[3524]: Successfully set preferred SMS mode: 'PDU' 15:05:02.766550 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:05:02.766593 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:05:02.766641 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:05:02.787064 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",69,60,129,18+CIEV: 1,5^SRVST:1' 15:05:02.787441 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",69,60,129,18' 15:05:02.787578 ModemManager[3524]: (ttyUSB1): <-- '^MODE:5,4' 15:05:02.787668 ModemManager[3524]: Access Technology: 'umts' 15:05:02.787809 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (unknown -> umts) 15:05:02.788532 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:05:02.788736 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:02.788840 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:05:02.841359 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:02.841548 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.841672 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="","SM","SM"' 15:05:02.875433 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:05:02.875527 ModemManager[3524]: Listing SMS parts in storage 'sm' 15:05:02.875597 ModemManager[3524]: Locking SMS storages to: mem1 (SM), mem2 (none)... 15:05:02.875687 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.875783 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.875896 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="SM"' 15:05:02.893317 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:05:02.893433 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:02.893547 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:02.893633 ModemManager[3524]: (ttyUSB1): --> 'AT+CMGL=4' 15:05:02.907930 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.908137 ModemManager[3524]: (ttyUSB1) Setting messaging unsolicited events handlers 15:05:02.908206 ModemManager[3524]: (ttyUSB2) Setting messaging unsolicited events handlers 15:05:02.908283 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.908410 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:02.908518 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0' 15:05:02.930297 ModemManager[3524]: (ttyUSB1): <-- '+CMS ERROR: 303' 15:05:02.930345 ModemManager[3524]: Got failure code 303: Operation not supported 15:05:02.930434 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0' 15:05:02.952816 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:02.952906 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:02.953040 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> searching) 15:05:03.411284 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 3,1' 15:05:03.411446 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:2' 15:05:03.411927 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",69,60,129,18+CIEV: 2,1+CIEV: 6,1' 15:05:03.412203 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:05:03.412972 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 1,"FFFE","1", 7+CGREG: 1,"FFFE","1",7^SRVST:2' 15:05:03.413008 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (searching -> registering) 15:05:03.413018 ModemManager[3524]: loading Operator Code... 15:05:03.413026 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:03.413034 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:05:03.462819 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,2,"310410",7OK' 15:05:03.463247 ModemManager[3524]: loaded Operator Code: 310410 15:05:03.463550 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP location updated (MCC: '310', MNC: '410', Location area code: '0', Cell ID: '0') 15:05:03.463592 ModemManager[3524]: loading Operator Name... 15:05:03.463602 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:03.464055 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:03.464666 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:05:03.500978 ModemManager[3524]: (ttyUSB1): <-- '^NWTIME: 13/09/03,22:05:05-28,01' 15:05:03.532438 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,0,"AT&T",7OK' 15:05:03.532926 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:03.533379 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:05:03.533861 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (searching -> registered) 15:05:03.535576 ModemManager[3524]: Periodic signal quality checks enabled (interval = 3s) 15:05:03.535829 ModemManager[3524]: loading signal quality... 15:05:03.535884 ModemManager[3524]: loading signal quality... 15:05:03.535934 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:03.535983 ModemManager[3524]: Periodic access technology checks enabled 15:05:03.535993 ModemManager[3524]: loading access technology (huawei)... 15:05:03.536034 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:03.537710 ModemManager[3524]: (ttyUSB1): --> 'AT+CIND?' 15:05:03.554383 ModemManager[3524]: (ttyUSB1): <-- '+CIND: ,5,1,1,0,0,1,0OK' 15:05:03.554668 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:03.554810 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:05:03.554849 ModemManager[3524]: Periodic signal quality checks rescheduled (interval = 30s) 15:05:03.554923 ModemManager[3524]: (ttyUSB1): --> 'AT^SYSINFOEX' 15:05:03.570001 ModemManager[3524]: (ttyUSB1): <-- '^SYSINFOEX: 2,3,0,1,,6,"LTE",101,"LTE"OK' 15:05:03.570167 ModemManager[3524]: Access Technology: 'lte' 15:05:03.570273 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (umts -> lte) 15:05:03.570316 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:14.248978 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> disabling) 15:05:14.249874 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (0) 15:05:14.249891 ModemManager[3524]: Periodic signal quality checks disabled 15:05:14.249897 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (lte -> unknown) 15:05:14.249902 ModemManager[3524]: Periodic access technology checks disabled 15:05:14.249907 ModemManager[3524]: Modem has messaging capabilities, disabling the Messaging interface... 15:05:14.249922 ModemManager[3524]: (ttyUSB1) Removing messaging unsolicited events handlers 15:05:14.249931 ModemManager[3524]: (ttyUSB2) Removing messaging unsolicited events handlers 15:05:14.250632 ModemManager[3524]: Modem has location capabilities, disabling the Location interface... 15:05:14.250767 ModemManager[3524]: Need to disable the following location sources: '3gpp-lac-ci' 15:05:14.250779 ModemManager[3524]: Disabled location '3gpp-lac-ci' gathering... 15:05:14.250985 ModemManager[3524]: Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface... 15:05:14.250999 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:14.251434 ModemManager[3524]: (ttyUSB1) Removing unsolicited result code handlers 15:05:14.251450 ModemManager[3524]: (ttyUSB2) Removing unsolicited result code handlers 15:05:14.251456 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:14.251461 ModemManager[3524]: Modem has 3GPP capabilities, disabling the Modem 3GPP interface... 15:05:14.251466 ModemManager[3524]: Periodic 3GPP registration checks disabled 15:05:14.251471 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:14.251475 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=0' 15:05:14.263719 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:14.263821 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:14.263920 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:14.263976 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=0' 15:05:14.275557 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:14.275575 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:14.275583 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:14.275588 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=0' 15:05:14.290143 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:14.290308 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:14.290328 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:14.290335 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=0' 15:05:14.302965 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:14.303011 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:14.303541 ModemManager[3524]: (ttyUSB1) cleaning up unsolicited registration messages handlers 15:05:14.303572 ModemManager[3524]: (ttyUSB2) cleaning up unsolicited registration messages handlers 15:05:14.303582 ModemManager[3524]: (ttyUSB1) Removing 3GPP unsolicited events handlers 15:05:14.303590 ModemManager[3524]: (ttyUSB2) Removing 3GPP unsolicited events handlers 15:05:14.303598 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:14.303605 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=0' 15:05:14.315124 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:14.315225 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:14.315326 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:14.315412 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=0' 15:05:14.327717 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:14.327786 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:14.327920 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:14.328009 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=0' 15:05:14.339470 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:14.339543 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> unknown) 15:05:14.339800 ModemManager[3524]: Bearer not allowed to connect, not registered in 3GPP network 15:05:14.339933 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:14.340664 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:05:14.340738 ModemManager[3524]: (ttyUSB1) closing serial port... 15:05:14.351957 ModemManager[3524]: (ttyUSB1) serial port closed 15:05:14.352072 ModemManager[3524]: (ttyUSB2) device open count is 0 (close) 15:05:14.352130 ModemManager[3524]: (ttyUSB2) closing serial port... 15:05:14.358664 ModemManager[3524]: (ttyUSB2) serial port closed 15:05:14.358702 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabling -> disabled) 15:05:14.362918 ModemManager[3524]: (ttyUSB1) opening serial port... 15:05:14.394058 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:05:14.394107 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:05:14.394209 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:05:14.409647 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1OK' 15:05:14.409812 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:14.409837 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:14.409849 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=0' 15:05:14.447481 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1' 15:05:14.889610 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:14.889699 ModemManager[3524]: Modem set in low-power mode... 15:05:14.889843 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:05:14.889949 ModemManager[3524]: (ttyUSB1) closing serial port... 15:05:14.901237 ModemManager[3524]: (ttyUSB1) serial port closed 15:05:16.085587 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disabled -> enabling) 15:05:16.086427 ModemManager[3524]: Skipping modem initialization: not first enabling 15:05:16.086450 ModemManager[3524]: (ttyUSB1) opening serial port... 15:05:16.117461 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:05:16.117500 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:05:16.117510 ModemManager[3524]: (ttyUSB1): running init sequence... 15:05:16.117518 ModemManager[3524]: (ttyUSB2) opening serial port... 15:05:16.134905 ModemManager[3524]: (ttyUSB2): port attributes not fully set 15:05:16.134949 ModemManager[3524]: (ttyUSB2) device open count is 1 (open) 15:05:16.134960 ModemManager[3524]: (ttyUSB2): running init sequence... 15:05:16.134968 ModemManager[3524]: Flashing primary AT port before enabling... 15:05:16.145189 ModemManager[3524]: (ttyUSB1): --> 'ATE0' 15:05:16.145234 ModemManager[3524]: (ttyUSB2): --> 'ATE0' 15:05:16.153486 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.153530 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:16.153669 ModemManager[3524]: (ttyUSB1): --> 'ATV1' 15:05:16.162846 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.162962 ModemManager[3524]: (ttyUSB1): --> 'AT+CMEE=1' 15:05:16.176839 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.176953 ModemManager[3524]: (ttyUSB1): --> 'ATX4' 15:05:16.183705 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.183742 ModemManager[3524]: (ttyUSB1): --> 'AT&C1' 15:05:16.190703 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.256173 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.256222 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH?' 15:05:16.271210 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 0,1OK' 15:05:16.271326 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.271432 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.271517 ModemManager[3524]: (ttyUSB1): --> 'AT^RFSWITCH=1' 15:05:16.328572 ModemManager[3524]: (ttyUSB1): <-- '^RFSWITCH: 1,1' 15:05:16.328675 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.328823 ModemManager[3524]: Modem set in full-power mode... 15:05:16.329002 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.329497 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.329649 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.329743 ModemManager[3524]: (ttyUSB1): --> 'AT+IFC=1,1' 15:05:16.400704 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.400753 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.400999 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.401101 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.401185 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS="UCS2"' 15:05:16.418327 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.418396 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.418497 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.418577 ModemManager[3524]: (ttyUSB1): --> 'AT+CSCS?' 15:05:16.430968 ModemManager[3524]: (ttyUSB1): <-- '+CSCS: "UCS2"OK' 15:05:16.431099 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.431199 ModemManager[3524]: Modem has 3GPP capabilities, enabling the Modem 3GPP interface... 15:05:16.431310 ModemManager[3524]: (ttyUSB1) Setting 3GPP unsolicited events handlers 15:05:16.431362 ModemManager[3524]: (ttyUSB2) Setting 3GPP unsolicited events handlers 15:05:16.431498 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.431605 ModemManager[3524]: (ttyUSB1): --> 'AT+CMER=3,0,0,1' 15:05:16.454057 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.454158 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:16.454264 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.454352 ModemManager[3524]: (ttyUSB2): --> 'AT+CMER=3,0,0,1' 15:05:16.482909 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:05:16.483045 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:0' 15:05:16.563030 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:16.563124 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.563236 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:16.563323 ModemManager[3524]: (ttyUSB1): --> 'AT^PORTSEL=0' 15:05:16.578381 ModemManager[3524]: (ttyUSB1): <-- 'ERROR' 15:05:16.578428 ModemManager[3524]: Got failure code 100: Unknown error 15:05:16.578492 ModemManager[3524]: (ttyUSB1): --> 'AT^CURC=1' 15:05:16.590070 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.590480 ModemManager[3524]: (ttyUSB1) setting up 3GPP unsolicited registration messages handlers 15:05:16.590518 ModemManager[3524]: (ttyUSB2) setting up 3GPP unsolicited registration messages handlers 15:05:16.590529 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.590618 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.590738 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG=2' 15:05:16.602368 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.602465 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:16.602574 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.602657 ModemManager[3524]: (ttyUSB2): --> 'AT+CREG=2' 15:05:16.614075 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:16.614148 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.614246 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:16.614330 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG=2' 15:05:16.629019 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.629044 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:16.629053 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.629418 ModemManager[3524]: (ttyUSB2): --> 'AT+CGREG=2' 15:05:16.641618 ModemManager[3524]: (ttyUSB2): <-- 'OK' 15:05:16.641666 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:16.641919 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:05:16.641944 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.641954 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:05:16.652246 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:05:16.652294 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> registering) 15:05:16.652307 ModemManager[3524]: loading Operator Code... 15:05:16.652366 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.652566 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:16.652647 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:16.652736 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:05:16.675248 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:16.675416 ModemManager[3524]: loading Operator Name... 15:05:16.675507 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:16.675592 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:16.675683 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:05:16.687206 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:05:16.687244 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (unknown -> searching) 15:05:16.687507 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.688071 ModemManager[3524]: Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... 15:05:16.688186 ModemManager[3524]: (ttyUSB1) Setting unsolicited result code handlers 15:05:16.688239 ModemManager[3524]: (ttyUSB2) Setting unsolicited result code handlers 15:05:16.689239 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.689474 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:05:16.711586 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:16.711753 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.711858 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:05:16.712508 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.713089 ModemManager[3524]: Modem has location capabilities, enabling the Location interface... 15:05:16.713247 ModemManager[3524]: Need to enable the following location sources: '3gpp-lac-ci' 15:05:16.713543 ModemManager[3524]: Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') 15:05:16.713673 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.713890 ModemManager[3524]: loading Operator Code... 15:05:16.713991 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.714396 ModemManager[3524]: Modem has messaging capabilities, enabling the Messaging interface... 15:05:16.714489 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:16.714549 ModemManager[3524]: (ttyUSB1): --> 'AT+CREG?' 15:05:16.724930 ModemManager[3524]: (ttyUSB1): <-- '+CREG: 2,2OK' 15:05:16.725024 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (home -> searching) 15:05:16.725453 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:05:16.725489 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:05:16.725914 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:05:16.769735 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:16.769955 ModemManager[3524]: loading Operator Name... 15:05:16.770049 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:05:16.770136 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:05:16.770241 ModemManager[3524]: Successfully set preferred SMS mode: 'PDU' 15:05:16.770390 ModemManager[3524]: (ttyUSB1) device open count is 5 (open) 15:05:16.770471 ModemManager[3524]: (ttyUSB1) device open count is 4 (close) 15:05:16.770557 ModemManager[3524]: (ttyUSB1): --> 'AT+CGREG?' 15:05:16.788731 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",67,60,171,18+CIEV: 1,5^SRVST:1^HCSQ: "LTE",67,60,171,18^MODE:5,4' 15:05:16.788811 ModemManager[3524]: Access Technology: 'umts' 15:05:16.788982 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (unknown -> umts) 15:05:16.824578 ModemManager[3524]: (ttyUSB1): <-- '+CGREG: 2,2OK' 15:05:16.824723 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:16.824846 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:05:16.862211 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0OK' 15:05:16.862398 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.862523 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="","SM","SM"' 15:05:16.889810 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:05:16.889947 ModemManager[3524]: Listing SMS parts in storage 'sm' 15:05:16.890018 ModemManager[3524]: Locking SMS storages to: mem1 (SM), mem2 (none)... 15:05:16.890108 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.890202 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.890290 ModemManager[3524]: (ttyUSB1): --> 'AT+CPMS="SM"' 15:05:16.907739 ModemManager[3524]: (ttyUSB1): <-- '+CPMS: 0,30,0,30,0,30OK' 15:05:16.907849 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:16.908005 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:16.908094 ModemManager[3524]: (ttyUSB1): --> 'AT+CMGL=4' 15:05:16.922335 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.922490 ModemManager[3524]: (ttyUSB1) Setting messaging unsolicited events handlers 15:05:16.922560 ModemManager[3524]: (ttyUSB2) Setting messaging unsolicited events handlers 15:05:16.922636 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.922760 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:16.922891 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,1,0' 15:05:16.943703 ModemManager[3524]: (ttyUSB1): <-- '+CMS ERROR: 303' 15:05:16.943741 ModemManager[3524]: Got failure code 303: Operation not supported 15:05:16.943816 ModemManager[3524]: (ttyUSB1): --> 'AT+CNMI=2,1,2,2,0' 15:05:16.966064 ModemManager[3524]: (ttyUSB1): <-- 'OK' 15:05:16.966126 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:16.966254 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (enabling -> searching) 15:05:17.338622 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 3,1^SRVST:2' 15:05:17.339101 ModemManager[3524]: (ttyUSB1): <-- '^HCSQ: "LTE",67,60,171,18+CIEV: 2,1' 15:05:17.339198 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:05:17.339355 ModemManager[3524]: (ttyUSB1): <-- '+CIEV: 6,1+CREG: 1,"FFFE","1", 7+CGREG: 1,"FFFE","1",7' 15:05:17.339441 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (searching -> registering) 15:05:17.339456 ModemManager[3524]: loading Operator Code... 15:05:17.339477 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:17.340611 ModemManager[3524]: (ttyUSB1): <-- '^SRVST:2' 15:05:17.340638 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,2;+COPS?' 15:05:17.386140 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,2,"310410",7OK' 15:05:17.386288 ModemManager[3524]: loaded Operator Code: 310410 15:05:17.386472 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP location updated (MCC: '310', MNC: '410', Location area code: '0', Cell ID: '0') 15:05:17.386529 ModemManager[3524]: loading Operator Name... 15:05:17.386602 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:17.386688 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:17.387219 ModemManager[3524]: (ttyUSB1): --> 'AT+COPS=3,0;+COPS?' 15:05:17.419571 ModemManager[3524]: (ttyUSB1): <-- '^NWTIME: 13/09/03,22:05:19-28,01' 15:05:17.450646 ModemManager[3524]: (ttyUSB1): <-- '+COPS: 0,0,"AT&T",7OK' 15:05:17.450741 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:17.450801 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: 3GPP Registration state changed (registering -> home) 15:05:17.450971 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (searching -> registered) 15:05:17.451735 ModemManager[3524]: Periodic signal quality checks enabled (interval = 3s) 15:05:17.454546 ModemManager[3524]: loading signal quality... 15:05:17.454798 ModemManager[3524]: loading signal quality... 15:05:17.454915 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:17.455382 ModemManager[3524]: Periodic access technology checks enabled 15:05:17.455447 ModemManager[3524]: loading access technology (huawei)... 15:05:17.455520 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:17.456663 ModemManager[3524]: (ttyUSB1): --> 'AT+CIND?' 15:05:17.480703 ModemManager[3524]: (ttyUSB1): <-- '+CIND: ,5,1,1,0,0,1,0OK' 15:05:17.480899 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:17.481047 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: signal quality updated (20) 15:05:17.481086 ModemManager[3524]: Periodic signal quality checks rescheduled (interval = 30s) 15:05:17.481141 ModemManager[3524]: (ttyUSB1): --> 'AT^SYSINFOEX' 15:05:17.499440 ModemManager[3524]: (ttyUSB1): <-- '^SYSINFOEX: 2,3,0,1,,6,"LTE",101,"LTE"OK' 15:05:17.499466 ModemManager[3524]: Access Technology: 'lte' 15:05:17.499472 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: access technology changed (umts -> lte) 15:05:17.499477 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:17.575064 ModemManager[3524]: Simple connect started... 15:05:17.575110 ModemManager[3524]: PIN: unspecified 15:05:17.575122 ModemManager[3524]: Operator ID: unspecified 15:05:17.575130 ModemManager[3524]: Allowed roaming: yes 15:05:17.575138 ModemManager[3524]: APN: pta 15:05:17.575145 ModemManager[3524]: IP family: unspecified 15:05:17.575152 ModemManager[3524]: Allowed authentication: unspecified 15:05:17.575161 ModemManager[3524]: User: unspecified 15:05:17.575168 ModemManager[3524]: Password: unspecified 15:05:17.575176 ModemManager[3524]: Number: #777 15:05:17.575183 ModemManager[3524]: Simple connect state (4/8): Wait to get fully enabled 15:05:17.575192 ModemManager[3524]: Simple connect state (5/8): Register 15:05:17.576357 ModemManager[3524]: Already registered in network '310410', automatic registration not launched... 15:05:17.576377 ModemManager[3524]: Simple connect state (6/8): Bearer 15:05:17.576386 ModemManager[3524]: Creating new bearer... 15:05:17.576393 ModemManager[3524]: Deleted disconnected bearer at '/org/freedesktop/ModemManager1/Bearer/1' 15:05:17.576401 ModemManager[3524]: Removing from DBus bearer at '/org/freedesktop/ModemManager1/Bearer/1' 15:05:17.576409 ModemManager[3524]: This device (usb0) can not support ndisdup feature 15:05:17.576417 ModemManager[3524]: ^NDISDUP not supported, creating default bearer... 15:05:17.576425 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:17.576432 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:17.576903 ModemManager[3524]: New huawei bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/2' 15:05:17.576915 ModemManager[3524]: Simple connect state (7/8): Connect 15:05:17.576924 ModemManager[3524]: Connecting bearer '/org/freedesktop/ModemManager1/Bearer/2' 15:05:17.576932 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting) 15:05:17.577353 ModemManager[3524]: Launching 3GPP connection attempt with APN 'pta' 15:05:17.577376 ModemManager[3524]: No specific IP family requested, defaulting to ipv4 15:05:17.577899 ModemManager[3524]: Looking for best CID... 15:05:17.577912 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:17.577920 ModemManager[3524]: (ttyUSB1): --> 'AT+CGDCONT?' 15:05:17.592567 ModemManager[3524]: (ttyUSB1): <-- '+CGDCONT: 1,"IP","","0.0.0.0",0,0+CGDCONT: 16,"IP","pta","0.0.0.0",0,0OK' 15:05:17.592591 ModemManager[3524]: Found '2' PDP contexts 15:05:17.592597 ModemManager[3524]: PDP context [cid=1] [type='ipv4'] [apn=''] 15:05:17.592602 ModemManager[3524]: PDP context [cid=16] [type='ipv4'] [apn='pta'] 15:05:17.592607 ModemManager[3524]: Found PDP context with CID 1 and no APN 15:05:17.592612 ModemManager[3524]: Found PDP context with CID 16 and PDP type ipv4 for APN 'pta' 15:05:17.592617 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:17.592621 ModemManager[3524]: Connection through a plain serial AT port (ttyUSB1) 15:05:17.592626 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:17.592646 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:17.592652 ModemManager[3524]: (ttyUSB1): --> 'ATD*99***16#' 15:05:17.608925 ModemManager[3524]: (ttyUSB1): <-- 'CONNECT 100000000' 15:05:17.609062 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:05:17.609152 ModemManager[3524]: (ttyUSB1): port now connected 15:05:17.609178 ModemManager[3524]: Connected bearer '/org/freedesktop/ModemManager1/Bearer/2' 15:05:17.609338 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> connected) 15:05:17.609920 ModemManager[3524]: Simple connect state (8/8): All done 15:05:17.739151 ModemManager[3524]: (net/ppp0): could not get port's parent device 15:05:17.936043 ModemManager[3524]: Disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/2' 15:05:17.936063 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connected -> disconnecting) 15:05:17.936070 ModemManager[3524]: Sending PDP context deactivation in secondary port... 15:05:17.936075 ModemManager[3524]: (ttyUSB2) device open count is 2 (open) 15:05:17.936080 ModemManager[3524]: (ttyUSB2): --> 'AT+CGACT=0,16' 15:05:17.955059 ModemManager[3524]: (ttyUSB2): <-- 'ERROR' 15:05:17.955088 ModemManager[3524]: Got failure code 100: Unknown error 15:05:17.955294 ModemManager[3524]: PDP context deactivation failed (not fatal): Unknown error 15:05:17.955306 ModemManager[3524]: Reopening data port (ttyUSB1)... 15:05:17.955312 ModemManager[3524]: (ttyUSB1) reopening port (2) 15:05:17.955317 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:17.955322 ModemManager[3524]: (ttyUSB1) device open count is 0 (close) 15:05:17.955327 ModemManager[3524]: (ttyUSB1) closing serial port... 15:05:17.955332 ModemManager[3524]: (ttyUSB1): port now disconnected 15:05:17.955337 ModemManager[3524]: (ttyUSB1) serial port closed 15:05:17.955342 ModemManager[3524]: (ttyUSB2) device open count is 1 (close) 15:05:18.956563 ModemManager[3524]: (ttyUSB1) opening serial port... 15:05:18.977051 ModemManager[3524]: (ttyUSB1): port attributes not fully set 15:05:18.977100 ModemManager[3524]: (ttyUSB1) device open count is 1 (open) 15:05:18.977110 ModemManager[3524]: (ttyUSB1): running init sequence... 15:05:18.977119 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:18.977598 ModemManager[3524]: Flashing data port (ttyUSB1)... 15:05:18.987403 ModemManager[3524]: (ttyUSB1): --> 'ATE0' 15:05:19.997807 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:19.997881 ModemManager[3524]: Sending PDP context deactivation in data port... 15:05:19.997925 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:22.443342 ModemManager[3524]: (ttyUSB1): --> 'ATV1' 15:05:25.886049 ModemManager[3524]: (ttyUSB1): --> 'AT+CMEE=1' 15:05:29.334958 ModemManager[3524]: (ttyUSB1): --> 'ATX4' 15:05:32.773236 ModemManager[3524]: (ttyUSB1): --> 'AT&C1' 15:05:36.215969 ModemManager[3524]: (ttyUSB1): --> 'AT+CGACT=0,16' 15:05:39.660790 ModemManager[3524]: PDP context deactivation failed (not fatal): Sending command failed: 'Resource temporarily unavailable' 15:05:39.660832 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:05:39.661158 ModemManager[3524]: Disconnected bearer '/org/freedesktop/ModemManager1/Bearer/2' 15:05:39.661176 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (disconnecting -> registered) 15:05:47.993848 ModemManager[3524]: loading access technology (huawei)... 15:05:47.993914 ModemManager[3524]: (ttyUSB1) device open count is 2 (open) 15:05:47.993950 ModemManager[3524]: loading signal quality... 15:05:47.993960 ModemManager[3524]: loading signal quality... 15:05:47.993968 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:05:47.993975 ModemManager[3524]: (ttyUSB1): --> 'AT^SYSINFOEX' 15:05:49.724575 ModemManager[3524]: Simple connect started... 15:05:49.724613 ModemManager[3524]: PIN: unspecified 15:05:49.724624 ModemManager[3524]: Operator ID: unspecified 15:05:49.724633 ModemManager[3524]: Allowed roaming: yes 15:05:49.724642 ModemManager[3524]: APN: pta 15:05:49.724650 ModemManager[3524]: IP family: unspecified 15:05:49.724659 ModemManager[3524]: Allowed authentication: unspecified 15:05:49.724668 ModemManager[3524]: User: unspecified 15:05:49.724676 ModemManager[3524]: Password: unspecified 15:05:49.724684 ModemManager[3524]: Number: #777 15:05:49.724721 ModemManager[3524]: Simple connect state (4/8): Wait to get fully enabled 15:05:49.724843 ModemManager[3524]: Simple connect state (5/8): Register 15:05:49.724968 ModemManager[3524]: Already registered in network '310410', automatic registration not launched... 15:05:49.725077 ModemManager[3524]: Simple connect state (6/8): Bearer 15:05:49.725136 ModemManager[3524]: Using already existing bearer at '/org/freedesktop/ModemManager1/Bearer/2'... 15:05:49.725180 ModemManager[3524]: Simple connect state (7/8): Connect 15:05:49.725241 ModemManager[3524]: Connecting bearer '/org/freedesktop/ModemManager1/Bearer/2' 15:05:49.725334 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (registered -> connecting) 15:05:49.726135 ModemManager[3524]: Launching 3GPP connection attempt with APN 'pta' 15:05:49.726197 ModemManager[3524]: No specific IP family requested, defaulting to ipv4 15:05:49.726239 ModemManager[3524]: Looking for best CID... 15:05:49.726310 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:51.447212 ModemManager[3524]: ^SYSINFOEX failed: Sending command failed: 'Resource temporarily unavailable' 15:05:51.447260 ModemManager[3524]: Couldn't refresh access technologies: 'Sending command failed: 'Resource temporarily unavailable'' 15:05:51.447276 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:51.447329 ModemManager[3524]: (ttyUSB1): --> 'AT+CIND?' 15:05:54.886580 ModemManager[3524]: (ttyUSB1) device open count is 4 (open) 15:05:54.886763 ModemManager[3524]: (ttyUSB1) device open count is 3 (close) 15:05:54.886787 ModemManager[3524]: (ttyUSB1): --> 'AT+CGDCONT?' 15:05:58.325277 ModemManager[3524]: No PDP contexts found 15:05:58.325402 ModemManager[3524]: (ttyUSB1): --> 'AT+CSQ' 15:06:01.764911 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:06:01.765091 ModemManager[3524]: Couldn't refresh signal quality: 'Sending command failed: 'Resource temporarily unavailable'' 15:06:01.765104 ModemManager[3524]: Unhandled PDP type in CGDCONT=? reply: 'PPP' 15:06:01.765110 ModemManager[3524]: Using CID 1 15:06:01.765302 ModemManager[3524]: (ttyUSB1) device open count is 3 (open) 15:06:01.765313 ModemManager[3524]: (ttyUSB1) device open count is 2 (close) 15:06:01.765318 ModemManager[3524]: (ttyUSB1): --> 'AT+CGDCONT=1,"IP","pta"' 15:06:05.206234 ModemManager[3524]: Couldn't initialize PDP context with our APN: 'Sending command failed: 'Resource temporarily unavailable'' 15:06:05.206281 ModemManager[3524]: (ttyUSB1) device open count is 1 (close) 15:06:05.207094 ModemManager[3524]: Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/2': 'Sending command failed: 'Resource temporarily unavailable'' 15:06:05.207126 ModemManager[3524]: Modem /org/freedesktop/ModemManager1/Modem/1: state changed (connecting -> registered) 15:06:05.207728 ModemManager[3524]: Couldn't connect bearer: 'Sending command failed: 'Resource temporarily unavailable'' 15:06:07.302291 ModemManager[3524]: (ttyUSB1): <-- 'NO CARRIER' 15:06:07.302427 ModemManager[3524]: Got failure code 1: No carrier