Sep 13 14:46:32 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 64 >>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:03:00:00:00:02:00:00:00:3C:00:00:00:02:00:00:00:FF:FF:00:00 Sep 13 14:46:32 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 64 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'signal-state' (0x0000000b) Sep 13 14:46:32 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'signal-state') Sep 13 14:46:32 vimes ModemManager[1360]: Signal state indication: 3 --> 9% Sep 13 14:46:32 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (9) Sep 13 14:46:34 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 64 >>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:05:00:00:00:04:00:00:00:3C:00:00:00:02:00:00:00:FF:FF:00:00 Sep 13 14:46:34 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 64 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'signal-state' (0x0000000b) Sep 13 14:46:34 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'signal-state') Sep 13 14:46:34 vimes ModemManager[1360]: Signal state indication: 5 --> 16% Sep 13 14:46:34 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (16) Sep 13 14:46:39 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 64 >>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:03:00:00:00:02:00:00:00:3C:00:00:00:02:00:00:00:FF:FF:00:00 Sep 13 14:46:39 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 64 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'signal-state' (0x0000000b) Sep 13 14:46:39 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'signal-state') Sep 13 14:46:39 vimes ModemManager[1360]: Signal state indication: 3 --> 9% Sep 13 14:46:39 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (9) Sep 13 14:46:42 vimes ModemManager[1360]: Simple connect started... Sep 13 14:46:42 vimes ModemManager[1360]: PIN: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: Operator ID: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: Allowed roaming: yes Sep 13 14:46:42 vimes ModemManager[1360]: APN: h2g2 Sep 13 14:46:42 vimes ModemManager[1360]: IP family: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: Allowed authentication: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: User: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: Password: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: Number: unspecified Sep 13 14:46:42 vimes ModemManager[1360]: Simple connect state (4/8): Wait to get fully enabled Sep 13 14:46:42 vimes ModemManager[1360]: Simple connect state (5/8): Register Sep 13 14:46:42 vimes ModemManager[1360]: Already registered in network '310260', automatic registration not launched... Sep 13 14:46:42 vimes NetworkManager[1435]: [1505339202.7752] (cdc-wdm0): modem state changed, 'registered' --> 'connecting' (reason: user-requested) Sep 13 14:46:42 vimes ModemManager[1360]: Simple connect state (6/8): Bearer Sep 13 14:46:42 vimes ModemManager[1360]: Creating new bearer... Sep 13 14:46:42 vimes ModemManager[1360]: Creating MBIM bearer in MBIM modem Sep 13 14:46:42 vimes ModemManager[1360]: New bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0' Sep 13 14:46:42 vimes ModemManager[1360]: Simple connect state (7/8): Connect Sep 13 14:46:42 vimes ModemManager[1360]: Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0' Sep 13 14:46:42 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting) Sep 13 14:46:42 vimes ModemManager[1360]: Launching connection with data port (net/wwp0s20u4) Sep 13 14:46:42 vimes ModemManager[1360]: Activating packet service... Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 52 <<<<<< data = 03:00:00:00:34:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:01:00:00:00:04:00:00:00:00:00:00:00 Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 52 <<<<<< type = command (0x00000003) <<<<<< transaction = 20 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'packet-service' (0x0000000a) <<<<<< type = 'set' (0x00000001) Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 76 >>>>>> data = 03:00:00:80:4C:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:00:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:80:F0:FA:02:00:00:00:00:00:E1:F5:05:00:00:00:00 Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 76 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 20 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'packet-service' (0x0000000a) Sep 13 14:46:42 vimes ModemManager[1360]: Packet service update: Sep 13 14:46:42 vimes ModemManager[1360]: state: 'attached' Sep 13 14:46:42 vimes ModemManager[1360]: data class: 'lte' Sep 13 14:46:42 vimes ModemManager[1360]: uplink: '50000000' bps Sep 13 14:46:42 vimes ModemManager[1360]: downlink: '100000000' bps Sep 13 14:46:42 vimes ModemManager[1360]: Listing provisioned contexts... Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:00:00:00:00 Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 21 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'provisioned-contexts' (0x0000000d) <<<<<< type = 'query' (0x00000000) Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 52 >>>>>> data = 03:00:00:80:34:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0D:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00 Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 52 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 21 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'provisioned-contexts' (0x0000000d) Sep 13 14:46:42 vimes ModemManager[1360]: Provisioned contexts found (0): Sep 13 14:46:42 vimes ModemManager[1360]: No specific IP family requested, defaulting to ipv4 Sep 13 14:46:42 vimes ModemManager[1360]: Launching ipv4 connection with APN 'h2g2'... Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message... <<<<<< RAW: <<<<<< length = 116 <<<<<< data = 03:00:00:00:74:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:01:00:00:00:44:00:00:00:00:00:00:00:01:00:00:00:3C:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:68:00:32:00:67:00:32:00 Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Sent message (translated)... <<<<<< Header: <<<<<< length = 116 <<<<<< type = command (0x00000003) <<<<<< transaction = 22 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'connect' (0x0000000c) <<<<<< type = 'set' (0x00000001) Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 72 >>>>>> data = 07:00:00:80:48:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:1C:00:00:00:00:00:00:00:04:00:00:00:00:00:00:00:80:F0:FA:02:00:00:00:00:00:E1:F5:05:00:00:00:00 Sep 13 14:46:42 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 72 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'packet-service' (0x0000000a) Sep 13 14:46:42 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'packet-service') Sep 13 14:46:42 vimes ModemManager[1360]: Packet service state: 'detached', data class: '(null)' Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 92 >>>>>> data = 07:00:00:80:5C:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:30:00:00:00:00:00:00:00:01:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00 Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 92 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'register-state' (0x00000009) Sep 13 14:46:43 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'register-state') Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> unknown) Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0', Location area code: '0', Cell ID: '0') Sep 13 14:46:43 vimes ModemManager[1360]: Bearer not allowed to connect, not registered in 3GPP network Sep 13 14:46:43 vimes ModemManager[1360]: Forcing disconnection of bearer '/org/freedesktop/ModemManager1/Bearer/0' Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (lte -> unknown) Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 64 >>>>>> data = 07:00:00:80:40:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0B:00:00:00:14:00:00:00:05:00:00:00:03:00:00:00:3C:00:00:00:02:00:00:00:FF:FF:00:00 Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 64 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'signal-state' (0x0000000b) Sep 13 14:46:43 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'signal-state') Sep 13 14:46:43 vimes ModemManager[1360]: Signal state indication: 5 --> 16% Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (16) Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 72 >>>>>> data = 07:00:00:80:48:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:1C:00:00:00:00:00:00:00:02:00:00:00:20:00:00:00:80:F0:FA:02:00:00:00:00:00:E1:F5:05:00:00:00:00 Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 72 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'packet-service' (0x0000000a) Sep 13 14:46:43 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'packet-service') Sep 13 14:46:43 vimes ModemManager[1360]: Packet service state: 'attached', data class: 'lte' Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 120 >>>>>> data = 07:00:00:80:78:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:4C:00:00:00:00:00:00:00:03:00:00:00:01:00:00:00:20:00:00:00:01:00:00:00:30:00:00:00:0C:00:00:00:3C:00:00:00:10:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00:33:00:31:00:30:00:32:00:36:00:30:00:54:00:2D:00:4D:00:6F:00:62:00:69:00:6C:00:65:00 Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 120 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'register-state' (0x00000009) Sep 13 14:46:43 vimes ModemManager[1360]: Received notification (service 'basic-connect', command 'register-state') Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown -> registering) Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown -> lte) Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '310', MNC: '260', Location area code: '0', Cell ID: '0') Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message... >>>>>> RAW: >>>>>> length = 84 >>>>>> data = 03:00:00:80:54:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0C:00:00:00:00:00:00:00:24:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:7E:5E:2A:7E:4E:6F:72:72:73:6B:65:6E:7E:5E:2A:7E:00:00:00:00 Sep 13 14:46:43 vimes ModemManager[1360]: [/dev/cdc-wdm0] Received message (translated)... >>>>>> Header: >>>>>> length = 84 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 22 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'connect' (0x0000000c) Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (registering -> home) Sep 13 14:46:43 vimes ModemManager[1360]: Session ID '0': activated (IP type: ipv4) Sep 13 14:46:43 vimes ModemManager[1360]: Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Connection setup operation has been cancelled' Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> disconnecting) Sep 13 14:46:43 vimes ModemManager[1360]: Couldn't connect bearer: 'Connection setup operation has been cancelled' Sep 13 14:46:43 vimes ModemManager[1360]: Error disconnecting bearer '/org/freedesktop/ModemManager1/Bearer/0': 'Couldn't disconnect MBIM bearer: this bearer is not connected'. Will assume disconnected anyway. Sep 13 14:46:43 vimes NetworkManager[1435]: [1505339203.7250] (cdc-wdm0): modem state changed, 'connecting' --> 'disconnecting' (reason: user-requested) Sep 13 14:46:43 vimes ModemManager[1360]: Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disconnecting -> registered) Sep 13 14:46:43 vimes NetworkManager[1435]: [1505339203.7268] (cdc-wdm0): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)