iOS: "Always" choice not given and status AUTHORIZATION_STATUS_ALWAYS is returned when we choose "When app is active" in alert

[HugoHeneault]

Hi Chris! 👋

A small issue about authorizations on iOS. Would you help us? Thanks!

Your Environment

• Plugin version: 1.8.0
• Platform: iOS
• OS version: 13.1.3
• Device manufacturer / model: iPhone 6S
• Flutter info (flutter doctor):

  
  Doctor summary (to see all details, run flutter doctor -v):
  [✓] Flutter (Channel stable, v1.17.2, on Mac OS X 10.15.5 19F101, locale fr-FR)

[✓] Android toolchain - develop for Android devices (Android SDK version 28.0.3) [✓] Xcode - develop for iOS and macOS (Xcode 11.5) [✓] Android Studio (version 3.6) [✓] Android Studio (version 4.0) [✓] VS Code (version 1.45.1) [✓] Connected device (3 available)

• No issues found!

* Plugin config:
```dart bg.Config(
        // Logging & Debug
        reset: true,
        debug: false,
        locationAuthorizationRequest: "Always",
        locationAuthorizationAlert: {
          'titleWhenNotEnabled': tr('gps_disabled_title'),
          'titleWhenOff': tr('gps_requires_always_title'),
          'instructions': tr('gps_requires_always_description'),
          'cancelButton': tr('cancel'),
          'settingsButton': tr('gps_disabled_settings')
        },
        logLevel: bg.Config.LOG_LEVEL_INFO,
        // Geolocation options
        desiredAccuracy: bg.Config.DESIRED_ACCURACY_HIGH,
        distanceFilter: 1,
        stopTimeout: 1,
        stopOnTerminate: true,
        heartbeatInterval: 60,
        desiredOdometerAccuracy: 10,
        isMoving: true,
        disableStopDetection: true,
        preventSuspend: true,
        pausesLocationUpdatesAutomatically: false,
        //Android notification
        notification: bg.Notification(
            title: hike is Hike
                ? hike.contents.get('title')
                : tr('geolocation_notification_title'),
            text: tr('geolocation_notification_text'),
            smallIcon: 'drawable/ic_marker'),
      )

Expected Behavior

User should be prompted to authorize "always" the app to access position

Actual Behavior

There is no choice with "Always" option.
When we choose "When in app is active", the providerChanged event returns AUTHORIZATION_STATUS_ALWAYS instead of AUTHORIZATION_STATUS_WHEN_IN_USE.

When I choose "When app is active" in settings I correctly get an onProviderChange event with status: AUTHORIZATION_STATUS_WHEN_IN_USE

Steps to Reproduce

1. Start the plugin with GPS authorization in "Ask" mode
2. Choose the "when app is active" option in the list of choices:

(the strings in french mean: "Authorize when app is active", "authorize once" and "refuse")

Context

Check that user always have AUTHORIZATION_STATUS_ALWAYS before tracking their position

Debug logs

Logs

``` 14:54:09.418075+0200 Runner 🔵-[TSLocationManager onResume:] enabled? 0 14:54:22.824369+0200 Runner ℹ️-[TSLocationManager removeListener:callback:] location 14:54:22.824434+0200 Runner ℹ️-[TSLocationManager on:success:failure:] location 14:54:22.824496+0200 Runner ℹ️-[TSLocationManager removeListener:callback:] providerchange 14:54:22.824556+0200 Runner ℹ️-[TSLocationManager on:success:failure:] providerchange 14:54:22.824594+0200 Runner ⚠️-[TSLocationManager log:message:] #ready already called. Redirecting to #setConfig 14:54:22.844409+0200 Runner ℹ️-[TSLocationManager doStart:] trackingMode: 1 14:54:22.848331+0200 Runner 🔵-[TSLocationManager setPace:] 1 14:54:22.922359+0200 Runner 🎾-[TSLocationManager startUpdatingLocation] Location-services: ON 14:54:22.927413+0200 Runner 🎾-[TSLocationManager startMonitoringBackgroundFetch] BackgroundFetch: ON 14:54:26.703547+0200 Runner 🔵-[TSLocationManager changePace:] isMoving: 1 14:54:26.704101+0200 Runner 🔵-[TSLocationManager setPace:] 1 14:54:26.707195+0200 Runner 🎾-[TSLocationManager startUpdatingLocation] Location-services: ON 14:54:28.666101+0200 Runner 🔵-[TSLocationManager locationManager:didChangeAuthorizationStatus:] status 3 14:54:28.670825+0200 Runner ℹ️-[TSLocationManager locationManager:didUpdateLocations:] Received stale motionchange location. Retrying... 14:54:28.714988+0200 Runner ℹ️-[TSLocationManager locationManager:didUpdateLocations:] Received stale motionchange location. Retrying... 14:54:28.988804+0200 Runner ✅-[TSLocationManager locationManager:didUpdateLocations:] Acquired motionchange position: <+45.18019592,+5.71993240> +/- 65.00m (speed -1.00 mps / course -1.00) @ 09/06/2020 14:54:28 heure d’été d’Europe centrale 14:54:29.011172+0200 Runner 🎾-[TSLocationManager startUpdatingLocation] Location-services: ON 14:54:29.015875+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 14:54:29.016256+0200 Runner 🎾-[TSLocationManager startMonitoringSignificantLocationChanges] 14:54:29.041892+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 0CBFDD07-52D8-4CE8-BFF7-08B8BFEFFF24 14:54:31.614537+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 14:54:31.614574+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 14:54:31.668122+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 6E9639BD-D5B5-492D-8E85-35F60EE31F90 14:54:31.674787+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: D5EF293E-AC66-4BE4-9942-F6D466CD4C45 14:54:32.918848+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 14:54:32.935889+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 55368D00-C2FC-482E-841C-1B6ECCEAAE51 14:54:33.713947+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 14:54:33.717756+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: E3A80E1F-2D78-40E1-BC92-710FE5AC98BD 14:54:46.052489+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 14:54:46.089349+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 3DCDF492-5FA0-4D90-8781-D92BA6E43A06 14:54:48.534007+0200 Runner 🔵-[TSLocationManager onSuspend:] enabled? 1) ```

Thanks for your help!

[christocracy]

This is normal. These are the result of iOS 13 changes to location authorization. I blogged about it here last year.

These changes are described in detail in the Apple video What's new in Core Location

[christocracy]

You'll be interested particularly at the time beginning at 3:40 and the phrase "Provisional Always Authorization".

[HugoHeneault]

Thanks for the blog post, got it! 👍

So there is no way to check if user manually gave the "Always" authorization? For my running app it means users will start tracking, put the phone in their pocket and have no idea that their localization isn't tracked anymore?

This is quite weird, we have no ability to check and coax the user to manually pick always?

[christocracy]

So there is no way to check if user gave the "Always" authorization?

Correct. The User Perspective vs What Your App Sees:

Later, when the delayed "Promote to Always Allow" dialog is presented by the OS, after the plugin has detected the device is moving, and the user selects Change to Only While Using, you will receive an onProviderChange event showing the user has selected WhenInUse.

At that point, you can begin bugging them to select Always.

[HugoHeneault]

Right. But if the we started the plugin in foreground mode, it should continue in the background, right? On my tests it doesn't seem to work this way.

[christocracy]

WhenInUse will continue tracking in background ONLY IF you engage location-services while the app is in the foreground with BackgroundGeolocation.changePace(true).

The plugin cannot automatically turn on location-services and initiate tracking while the app is in the background with WhenInUse authorization. This has always been the case.

Example: A jogging app with WhenInUse authorization where the user clicks a [Start Workout] button.

In the /example app in this repo, this is the green [ > ] in the bottom toolbar.

[HugoHeneault]

Great! Thanks. I'm gonna check that later :)

Your blog post and the video you gave me should really be added on the https://pub.dev/documentation/flutter_background_geolocation/latest/flt_background_geolocation/ProviderChangeEvent-class.html page or somewhere we can understand how it works. 📚

[HugoHeneault]

Hello @christocracy

I should reopen this issue as we really don't get locations update when 1st launched app got "when app is active mode" authorization and screen got locked.

Here are some logs with this timeline:

1. First app launch
2. Start plugin at 09:30:28
3. Gave the "when app is active" authorization at 09:30:32
4. Locked the device around 09:30:44
5. Locations update stop until 09:31:57 when I unlock the device.

Did I got something wrong?

``` 09:29:30.821531+0200 Runner Task .<1> resuming, QOS(0x15) Voucher (null) 09:29:30.821811+0200 Runner Task .<1> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:29:30.823129+0200 Runner Connection 8: enabling TLS 09:29:30.823215+0200 Runner Connection 8: starting, TC(0x0) 09:29:30.823395+0200 Runner [C8 EC4E1043-4005-4FA3-B2D6-81020D269F47 firebaselogging-pa.googleapis.com:443 tcp, url hash: e23dcc20, tls] start 09:29:30.825397+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C8] reporting state preparing 09:29:30.829838+0200 Runner Task .<1> setting up Connection 8 09:29:30.837922+0200 Runner tcp_output [C8.1:3] flags=[S] seq=3168799455, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=3168799455 09:29:30.870862+0200 Runner tcp_input [C8.1:3] flags=[S.] seq=4019208678, ack=3168799456, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=3168799455 09:29:30.871308+0200 Runner nw_flow_connected [C8.1 216.58.204.106:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:29:30.873140+0200 Runner boringssl_context_set_handshake_config(1472) [0x14db06270] set tls_handshake_config_standard 09:29:30.873334+0200 Runner boringssl_context_set_min_version(326) [0x14db06270] set 0x0301 09:29:30.873519+0200 Runner boringssl_context_set_max_version(310) [0x14db06270] set 0x0304 09:29:30.873713+0200 Runner boringssl_context_set_cipher_suites(844) [0x14db06270] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:29:30.874516+0200 Runner boringssl_context_set_remote_address(2556) [0x14db06270] Saving remote IPv4 address 09:29:30.874666+0200 Runner boringssl_session_install_association_state(1289) [0x14db06270] Client session cache miss 09:29:30.874810+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14db06270] SNI firebaselogging-pa.googleapis.com 09:29:30.875006+0200 Runner boringssl_context_set_min_version(326) [C8.1:2][0x14db06270] set 0x0303 09:29:30.875194+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C8.1:2][0x14db06270] set false 09:29:30.875346+0200 Runner boringssl_context_set_false_start(411) [C8.1:2][0x14db06270] set false 09:29:30.875488+0200 Runner boringssl_context_set_enforce_ev(401) [C8.1:2][0x14db06270] set false 09:29:30.875583+0200 Runner boringssl_context_set_ats_enforced(1312) [C8.1:2][0x14db06270] set false 09:29:30.875679+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C8.1:2][0x14db06270] set 0 09:29:30.875907+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C8.1:2][0x14db06270] set 0 09:29:30.876051+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C8.1:2][0x14db06270] set 0 09:29:30.876146+0200 Runner boringssl_session_set_peer_hostname(1181) [C8.1:2][0x14db06270] SNI firebaselogging-pa.googleapis.com 09:29:30.876224+0200 Runner boringssl_context_set_cipher_suites(844) [C8.1:2][0x14db06270] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:29:30.876385+0200 Runner nw_protocol_boringssl_begin_connection(495) [C8.1:2][0x14db06270] early data disabled 09:29:30.876569+0200 Runner boringssl_context_info_handler(1971) [C8.1:2][0x14db06270] Client handshake started 09:29:30.876686+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Writing SSL3_RT_HANDSHAKE 512 bytes 09:29:30.876849+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS client enter_early_data 09:29:30.876993+0200 Runner boringssl_context_add_handshake_message_pending(579) [C8.1:2][0x14db06270] Adding message(1) 09:29:30.877135+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS client read_server_hello 09:29:30.877329+0200 Runner boringssl_context_add_handshake_message_pending(579) [C8.1:2][0x14db06270] Adding message(2) 09:29:30.877470+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:30.877562+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:30.877763+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:30.878110+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:30.895478+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:30.895592+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:30.900239+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 122 bytes 09:29:30.900437+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_hello_retry_request 09:29:30.900659+0200 Runner boringssl_context_add_handshake_message_pending(579) [C8.1:2][0x14db06270] Adding message(2) 09:29:30.900814+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:29:30.900949+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_server_hello 09:29:30.901216+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:29:30.901336+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 15 bytes 09:29:30.901448+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_certificate_request 09:29:30.901592+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 2634 bytes 09:29:30.901895+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_server_certificate 09:29:30.902014+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:29:30.902124+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 79 bytes 09:29:30.902304+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C8.1:2][0x14db06270] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:30.902650+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C8.1:2][0x14db06270] SecTrustCreateWithCertificates result: 0 09:29:30.902770+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C8.1:2][0x14db06270] No TLS-provided OCSP response 09:29:30.902883+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C8.1:2][0x14db06270] No TLS-provided SCTs 09:29:30.903381+0200 Runner boringssl_context_certificate_verify_callback(2072) [C8.1:2][0x14db06270] Asyncing for verify block 09:29:30.903493+0200 Runner boringssl_session_handshake_incomplete(191) [C8.1:2][0x14db06270] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:29:30.903709+0200 Runner Connection 8: asked to evaluate TLS Trust 09:29:30.904021+0200 Runner Task .<1> auth completion disp=1 cred=0x0 09:29:30.914472+0200 Runner System Trust Evaluation yielded status(0) 09:29:30.922755+0200 Runner Connection 8: TLS Trust result 0 09:29:30.922883+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C8.1:2][0x14db06270] Returning from verify block 09:29:30.923079+0200 Runner boringssl_context_certificate_verify_callback(2048) [C8.1:2][0x14db06270] Setting trust result to ssl_verify_ok 09:29:30.923904+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client read_server_finished 09:29:30.924000+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 36 bytes 09:29:30.924283+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client send_end_of_early_data 09:29:30.924386+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client send_client_certificate 09:29:30.924493+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client complete_second_flight 09:29:30.924612+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Writing SSL3_RT_HANDSHAKE 36 bytes 09:29:30.924922+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS 1.3 client done 09:29:30.924998+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS client finish_client_handshake 09:29:30.925494+0200 Runner boringssl_context_info_handler(1984) [C8.1:2][0x14db06270] Client handshake state: TLS client done 09:29:30.925584+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C8.1:2][0x14db06270] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:30.926634+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C8.1:2][0x14db06270] SecTrustCreateWithCertificates result: 0 09:29:30.926737+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C8.1:2][0x14db06270] No TLS-provided OCSP response 09:29:30.926898+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C8.1:2][0x14db06270] No TLS-provided SCTs 09:29:30.927098+0200 Runner boringssl_context_add_handshake_message_pending(579) [C8.1:2][0x14db06270] Adding message(20) 09:29:30.927418+0200 Runner boringssl_context_info_handler(1975) [C8.1:2][0x14db06270] Client handshake done 09:29:30.927548+0200 Runner nw_protocol_boringssl_signal_connected(699) [C8.1:2][0x14db06270] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:29:30.927871+0200 Runner nw_flow_connected [C8.1 216.58.204.106:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:29:30.929035+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C8] reporting state ready 09:29:30.929714+0200 Runner Connection 8: connected successfully 09:29:30.929841+0200 Runner Connection 8: TLS handshake complete 09:29:30.930146+0200 Runner Connection 8: ready C(N) E(N) 09:29:30.930289+0200 Runner new connection to firebaselogging-pa.googleapis.com config 0x280eb5100 09:29:30.930383+0200 Runner Task .<1> now using Connection 8 09:29:30.930493+0200 Runner Connection 8: received viability advisory(Y) 09:29:30.930562+0200 Runner Task .<1> sent request, body S 444 09:29:30.944231+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:30.944292+0200 Runner boringssl_context_new_session_handler(1118) [C8.1:2][0x14db06270] New session available 09:29:30.944473+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:30.944518+0200 Runner boringssl_context_new_session_handler(1118) [C8.1:2][0x14db06270] New session available 09:29:30.959553+0200 Runner Task .<1> received response, status 200 content K 09:29:30.959630+0200 Runner Task .<1> done using Connection 8 09:29:30.960836+0200 Runner Task .<1> response ended 09:29:30.962275+0200 Runner Task .<1> summary for task success {transaction_duration_ms=140, response_status=200, connection=8, protocol="h2", domain_lookup_duration_ms=8, connect_duration_ms=89, secure_connection_duration_ms=52, request_start_ms=110, request_duration_ms=0, response_start_ms=139, response_duration_ms=0, request_bytes=634, response_bytes=588, cache_hit=0} 09:29:45.863713+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> resuming, QOS(0x19) Voucher 09:29:45.864267+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:29:45.865128+0200 Runner Connection 9: enabling TLS 09:29:45.865283+0200 Runner Connection 9: starting, TC(0x0) 09:29:45.865514+0200 Runner [C9 44EAFB9D-4954-4EA1-A1E5-C89393AA4257 play.googleapis.com:443 tcp, url hash: 7efd1bc6, tls] start 09:29:45.867177+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C9] reporting state preparing 09:29:45.868949+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> setting up Connection 9 09:29:45.877464+0200 Runner tcp_output [C9.1:3] flags=[S] seq=590419235, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=590419235 09:29:45.902925+0200 Runner tcp_input [C9.1:3] flags=[S.] seq=3904250065, ack=590419236, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=590419235 09:29:45.903040+0200 Runner nw_flow_connected [C9.1 172.217.22.138:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:29:45.903861+0200 Runner boringssl_context_set_handshake_config(1472) [0x14db6fee0] set tls_handshake_config_standard 09:29:45.903968+0200 Runner boringssl_context_set_min_version(326) [0x14db6fee0] set 0x0301 09:29:45.904089+0200 Runner boringssl_context_set_max_version(310) [0x14db6fee0] set 0x0304 09:29:45.904212+0200 Runner boringssl_context_set_cipher_suites(844) [0x14db6fee0] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:29:45.904448+0200 Runner boringssl_context_set_remote_address(2556) [0x14db6fee0] Saving remote IPv4 address 09:29:45.904669+0200 Runner boringssl_session_install_association_state(1289) [0x14db6fee0] Client session cache miss 09:29:45.904850+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14db6fee0] SNI play.googleapis.com 09:29:45.905019+0200 Runner boringssl_context_set_min_version(326) [C9.1:2][0x14db6fee0] set 0x0303 09:29:45.905090+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C9.1:2][0x14db6fee0] set false 09:29:45.905157+0200 Runner boringssl_context_set_false_start(411) [C9.1:2][0x14db6fee0] set false 09:29:45.905258+0200 Runner boringssl_context_set_enforce_ev(401) [C9.1:2][0x14db6fee0] set false 09:29:45.905347+0200 Runner boringssl_context_set_ats_enforced(1312) [C9.1:2][0x14db6fee0] set false 09:29:45.905420+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C9.1:2][0x14db6fee0] set 0 09:29:45.905524+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C9.1:2][0x14db6fee0] set 0 09:29:45.905608+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C9.1:2][0x14db6fee0] set 0 09:29:45.905725+0200 Runner boringssl_session_set_peer_hostname(1181) [C9.1:2][0x14db6fee0] SNI play.googleapis.com 09:29:45.905821+0200 Runner boringssl_context_set_cipher_suites(844) [C9.1:2][0x14db6fee0] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:29:45.905894+0200 Runner nw_protocol_boringssl_begin_connection(495) [C9.1:2][0x14db6fee0] early data disabled 09:29:45.905977+0200 Runner boringssl_context_info_handler(1971) [C9.1:2][0x14db6fee0] Client handshake started 09:29:45.906049+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Writing SSL3_RT_HANDSHAKE 512 bytes 09:29:45.906142+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS client enter_early_data 09:29:45.906229+0200 Runner boringssl_context_add_handshake_message_pending(579) [C9.1:2][0x14db6fee0] Adding message(1) 09:29:45.906299+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS client read_server_hello 09:29:45.906367+0200 Runner boringssl_context_add_handshake_message_pending(579) [C9.1:2][0x14db6fee0] Adding message(2) 09:29:45.906490+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.906641+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.906764+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.906872+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.925112+0200 Runner Task .<1> resuming, QOS(0x19) Voucher 09:29:45.925159+0200 Runner Task .<1> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:29:45.925607+0200 Runner Connection 10: enabling TLS 09:29:45.925651+0200 Runner Connection 10: starting, TC(0x0) 09:29:45.925690+0200 Runner [C10 5F557076-857E-43E8-A3A0-1FEAFD3DAC4C www.googleapis.com:443 tcp, url hash: d47ec334, tls] start 09:29:45.926872+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C10] reporting state preparing 09:29:45.928354+0200 Runner Task .<1> setting up Connection 10 09:29:45.940265+0200 Runner tcp_output [C10.1:3] flags=[S] seq=2656038915, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=2656038915 09:29:45.940376+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.940466+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.940557+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 122 bytes 09:29:45.940632+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_hello_retry_request 09:29:45.940781+0200 Runner boringssl_context_add_handshake_message_pending(579) [C9.1:2][0x14db6fee0] Adding message(2) 09:29:45.940828+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:29:45.940875+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_server_hello 09:29:45.941317+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:29:45.941376+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 15 bytes 09:29:45.941468+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_certificate_request 09:29:45.941507+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 2634 bytes 09:29:45.941538+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_server_certificate 09:29:45.941616+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:29:45.941653+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 78 bytes 09:29:45.941684+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C9.1:2][0x14db6fee0] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:45.941917+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C9.1:2][0x14db6fee0] SecTrustCreateWithCertificates result: 0 09:29:45.941956+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C9.1:2][0x14db6fee0] No TLS-provided OCSP response 09:29:45.942024+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C9.1:2][0x14db6fee0] No TLS-provided SCTs 09:29:45.942240+0200 Runner boringssl_context_certificate_verify_callback(2072) [C9.1:2][0x14db6fee0] Asyncing for verify block 09:29:45.942343+0200 Runner boringssl_session_handshake_incomplete(191) [C9.1:2][0x14db6fee0] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:29:45.942439+0200 Runner Connection 9: asked to evaluate TLS Trust 09:29:45.954803+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> auth completion disp=0 cred=0x280c92d30 09:29:45.968185+0200 Runner tcp_input [C10.1:3] flags=[S.] seq=185454909, ack=2656038916, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=2656038915 09:29:45.968226+0200 Runner nw_flow_connected [C10.1 216.58.201.234:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:29:45.969649+0200 Runner boringssl_context_set_handshake_config(1472) [0x14db35a30] set tls_handshake_config_standard 09:29:45.969750+0200 Runner boringssl_context_set_min_version(326) [0x14db35a30] set 0x0301 09:29:45.969841+0200 Runner boringssl_context_set_max_version(310) [0x14db35a30] set 0x0304 09:29:45.969907+0200 Runner boringssl_context_set_cipher_suites(844) [0x14db35a30] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:29:45.970168+0200 Runner boringssl_context_set_remote_address(2556) [0x14db35a30] Saving remote IPv4 address 09:29:45.970247+0200 Runner boringssl_session_install_association_state(1289) [0x14db35a30] Client session cache miss 09:29:45.970299+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14db35a30] SNI www.googleapis.com 09:29:45.970331+0200 Runner boringssl_context_set_min_version(326) [C10.1:2][0x14db35a30] set 0x0303 09:29:45.970401+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C10.1:2][0x14db35a30] set false 09:29:45.970434+0200 Runner boringssl_context_set_false_start(411) [C10.1:2][0x14db35a30] set false 09:29:45.970479+0200 Runner boringssl_context_set_enforce_ev(401) [C10.1:2][0x14db35a30] set false 09:29:45.970518+0200 Runner boringssl_context_set_ats_enforced(1312) [C10.1:2][0x14db35a30] set false 09:29:45.970561+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C10.1:2][0x14db35a30] set 0 09:29:45.970614+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C10.1:2][0x14db35a30] set 0 09:29:45.970664+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C10.1:2][0x14db35a30] set 0 09:29:45.970695+0200 Runner boringssl_session_set_peer_hostname(1181) [C10.1:2][0x14db35a30] SNI www.googleapis.com 09:29:45.970760+0200 Runner boringssl_context_set_cipher_suites(844) [C10.1:2][0x14db35a30] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:29:45.970802+0200 Runner nw_protocol_boringssl_begin_connection(495) [C10.1:2][0x14db35a30] early data disabled 09:29:45.970875+0200 Runner boringssl_context_info_handler(1971) [C10.1:2][0x14db35a30] Client handshake started 09:29:45.971060+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Writing SSL3_RT_HANDSHAKE 512 bytes 09:29:45.971092+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS client enter_early_data 09:29:45.971143+0200 Runner boringssl_context_add_handshake_message_pending(579) [C10.1:2][0x14db35a30] Adding message(1) 09:29:45.971175+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS client read_server_hello 09:29:45.971230+0200 Runner boringssl_context_add_handshake_message_pending(579) [C10.1:2][0x14db35a30] Adding message(2) 09:29:45.971282+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.971319+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.971379+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.971410+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.972106+0200 Runner System Trust Evaluation yielded status(0) 09:29:45.972136+0200 Runner Connection 9: TLS Trust result 0 09:29:45.972183+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C9.1:2][0x14db6fee0] Returning from verify block 09:29:45.972295+0200 Runner boringssl_context_certificate_verify_callback(2048) [C9.1:2][0x14db6fee0] Setting trust result to ssl_verify_ok 09:29:45.972346+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client read_server_finished 09:29:45.972428+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 36 bytes 09:29:45.972482+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client send_end_of_early_data 09:29:45.972610+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client send_client_certificate 09:29:45.972649+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client complete_second_flight 09:29:45.972729+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Writing SSL3_RT_HANDSHAKE 36 bytes 09:29:45.972789+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS 1.3 client done 09:29:45.972850+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS client finish_client_handshake 09:29:45.972887+0200 Runner boringssl_context_info_handler(1984) [C9.1:2][0x14db6fee0] Client handshake state: TLS client done 09:29:45.972944+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C9.1:2][0x14db6fee0] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:45.973050+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C9.1:2][0x14db6fee0] SecTrustCreateWithCertificates result: 0 09:29:45.973107+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C9.1:2][0x14db6fee0] No TLS-provided OCSP response 09:29:45.973144+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C9.1:2][0x14db6fee0] No TLS-provided SCTs 09:29:45.973227+0200 Runner boringssl_context_add_handshake_message_pending(579) [C9.1:2][0x14db6fee0] Adding message(20) 09:29:45.973273+0200 Runner boringssl_context_info_handler(1975) [C9.1:2][0x14db6fee0] Client handshake done 09:29:45.973402+0200 Runner nw_protocol_boringssl_signal_connected(699) [C9.1:2][0x14db6fee0] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:29:45.973532+0200 Runner nw_flow_connected [C9.1 172.217.22.138:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:29:45.973799+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C9] reporting state ready 09:29:45.974147+0200 Runner Connection 9: connected successfully 09:29:45.974215+0200 Runner Connection 9: TLS handshake complete 09:29:45.974256+0200 Runner Connection 9: ready C(N) E(N) 09:29:45.974350+0200 Runner new connection to play.googleapis.com config 0x280e3d0c0 09:29:45.974395+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> now using Connection 9 09:29:45.974443+0200 Runner Connection 9: received viability advisory(Y) 09:29:45.974520+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> sent request, body S 1190 09:29:45.984613+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:45.984800+0200 Runner boringssl_context_new_session_handler(1118) [C9.1:2][0x14db6fee0] New session available 09:29:45.984956+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:45.985017+0200 Runner boringssl_context_new_session_handler(1118) [C9.1:2][0x14db6fee0] New session available 09:29:45.987045+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.987123+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:45.993180+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 122 bytes 09:29:45.993345+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_hello_retry_request 09:29:45.993460+0200 Runner boringssl_context_add_handshake_message_pending(579) [C10.1:2][0x14db35a30] Adding message(2) 09:29:45.993575+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:29:45.993690+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_server_hello 09:29:45.993835+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:29:45.993947+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 15 bytes 09:29:45.994055+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_certificate_request 09:29:45.994174+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 2634 bytes 09:29:45.994383+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_server_certificate 09:29:45.994424+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:29:45.994508+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 79 bytes 09:29:45.994548+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C10.1:2][0x14db35a30] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:45.994673+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C10.1:2][0x14db35a30] SecTrustCreateWithCertificates result: 0 09:29:45.994776+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C10.1:2][0x14db35a30] No TLS-provided OCSP response 09:29:45.994915+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C10.1:2][0x14db35a30] No TLS-provided SCTs 09:29:45.995152+0200 Runner boringssl_context_certificate_verify_callback(2072) [C10.1:2][0x14db35a30] Asyncing for verify block 09:29:45.995258+0200 Runner boringssl_session_handshake_incomplete(191) [C10.1:2][0x14db35a30] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:29:45.995520+0200 Runner Connection 10: asked to evaluate TLS Trust 09:29:46.008245+0200 Runner Task .<1> auth completion disp=0 cred=0x280c92c30 09:29:46.008368+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> received response, status 200 content K 09:29:46.008491+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> done using Connection 9 09:29:46.008718+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> response ended 09:29:46.008800+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> summary for task success {transaction_duration_ms=137, response_status=200, connection=9, protocol="h2", domain_lookup_duration_ms=5, connect_duration_ms=90, secure_connection_duration_ms=63, request_start_ms=105, request_duration_ms=0, response_start_ms=136, response_duration_ms=1, request_bytes=1343, response_bytes=583, cache_hit=0} 09:29:46.008881+0200 Runner Task <3DF0D3D1-AD0C-4A1E-99EA-6D7A453BED35>.<1> finished successfully 09:29:46.013262+0200 Runner removing all entries config 0x280e3d0c0 09:29:46.013686+0200 Runner removing all entries config 0x280e3d0c0 09:29:46.014388+0200 Runner Connection 9: is being canceled 09:29:46.014476+0200 Runner [C9 44EAFB9D-4954-4EA1-A1E5-C89393AA4257 play.googleapis.com:443 tcp, url hash: 7efd1bc6, tls] cancel 09:29:46.016730+0200 Runner [C9 44EAFB9D-4954-4EA1-A1E5-C89393AA4257 play.googleapis.com:443 tcp, url hash: 7efd1bc6, tls] cancelled [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 0.139s, DNS @0.001s took 0.005s, TCP @0.011s took 0.025s, TLS took 0.065s bytes in/out: 4217/2223, packets in/out: 8/11, rtt: 0.021s, retransmitted packets: 0, out-of-order packets: 0 09:29:46.016877+0200 Runner 0.000s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:29:46.016985+0200 Runner 0.000s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:29:46.017188+0200 Runner 0.001s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:29:46.017317+0200 Runner 0.006s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:29:46.017519+0200 Runner 0.007s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:29:46.017759+0200 Runner 0.007s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:29:46.017863+0200 Runner 0.008s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:29:46.018588+0200 Runner 0.009s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:29:46.018629+0200 Runner 0.011s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:29:46.018662+0200 Runner 0.024s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:29:46.018698+0200 Runner 0.036s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:29:46.018728+0200 Runner 0.036s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:29:46.018775+0200 Runner 0.101s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:29:46.018848+0200 Runner 0.101s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:29:46.018904+0200 Runner 0.101s [C9.1 41CE653E-929E-408A-AA8E-D92829FE4E4A 192.168.0.25:53753<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:29:46.018958+0200 Runner 0.102s [C9 D32B189B-C71D-4154-811C-47FBCF0E580E play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:29:46.019221+0200 Runner 0.139s [C9] path:cancel 09:29:46.019557+0200 Runner boringssl_context_message_handler(2259) [C9.1:2][0x14db6fee0] Writing SSL3_RT_ALERT 2 bytes 09:29:46.019612+0200 Runner boringssl_context_handle_warning_alert(1894) [C9.1:2][0x14db6fee0] write alert, level: warning, description: close notify 09:29:46.019650+0200 Runner boringssl_session_disconnect(539) [C9.1:2][0x14db6fee0] SSL_shutdown 0 09:29:46.019845+0200 Runner nw_protocol_tcp_log_summary [C9.1:3] [81A41637-D628-4993-851F-71A1E42D3BAC 192.168.0.25:53753<->172.217.22.138:443] Init: 1, Conn_Time: 24.660ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: process, rtt_upd: 4, rtt: 21.468ms, rtt_var: 4.750ms rtt_nc: 23.812ms, rtt_var_nc: 8.937ms 09:29:46.019952+0200 Runner nw_flow_disconnected [C9.1 172.217.22.138:443 cancelled channel-flow ((null))] Output protocol disconnected 09:29:46.020035+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C9] reporting state cancelled 09:29:46.020117+0200 Runner Connection 9: destroyed 09:29:46.020224+0200 Runner tcp_output [C9.1:3] flags=[F.] seq=590421483, ack=3904254283, win=1024 state=FIN_WAIT_1 rcv_nxt=3904254283, snd_una=590421350 09:29:46.020262+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C9.1:2][0x14db6fee0] nw_protocol_boringssl_remove_input_handler forced true 09:29:46.020325+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C9.1:2][0x14db6fee0] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:29:46.020452+0200 Runner System Trust Evaluation yielded status(0) 09:29:46.020493+0200 Runner Connection 10: TLS Trust result 0 09:29:46.020582+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C10.1:2][0x14db35a30] Returning from verify block 09:29:46.020658+0200 Runner boringssl_context_certificate_verify_callback(2048) [C10.1:2][0x14db35a30] Setting trust result to ssl_verify_ok 09:29:46.020701+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client read_server_finished 09:29:46.020785+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 36 bytes 09:29:46.020838+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client send_end_of_early_data 09:29:46.020906+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client send_client_certificate 09:29:46.020966+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client complete_second_flight 09:29:46.021013+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Writing SSL3_RT_HANDSHAKE 36 bytes 09:29:46.021110+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS 1.3 client done 09:29:46.021188+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS client finish_client_handshake 09:29:46.021249+0200 Runner boringssl_context_info_handler(1984) [C10.1:2][0x14db35a30] Client handshake state: TLS client done 09:29:46.021308+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C10.1:2][0x14db35a30] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:46.021560+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> resuming, QOS(0x19) Voucher 09:29:46.021610+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C10.1:2][0x14db35a30] SecTrustCreateWithCertificates result: 0 09:29:46.021652+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C10.1:2][0x14db35a30] No TLS-provided OCSP response 09:29:46.021697+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C10.1:2][0x14db35a30] No TLS-provided SCTs 09:29:46.021816+0200 Runner boringssl_context_add_handshake_message_pending(579) [C10.1:2][0x14db35a30] Adding message(20) 09:29:46.021873+0200 Runner boringssl_context_info_handler(1975) [C10.1:2][0x14db35a30] Client handshake done 09:29:46.021929+0200 Runner nw_protocol_boringssl_signal_connected(699) [C10.1:2][0x14db35a30] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:29:46.022125+0200 Runner nw_flow_connected [C10.1 216.58.201.234:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:29:46.022389+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C10] reporting state ready 09:29:46.022630+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:29:46.022660+0200 Runner Connection 10: connected successfully 09:29:46.022712+0200 Runner Connection 10: TLS handshake complete 09:29:46.022833+0200 Runner Connection 10: ready C(N) E(N) 09:29:46.022932+0200 Runner new connection to www.googleapis.com config 0x280ec1840 09:29:46.022978+0200 Runner Task .<1> now using Connection 10 09:29:46.023013+0200 Runner Connection 10: received viability advisory(Y) 09:29:46.023081+0200 Runner Task .<1> sent request, body S 186 09:29:46.023148+0200 Runner Connection 11: enabling TLS 09:29:46.023195+0200 Runner Connection 11: starting, TC(0x0) 09:29:46.023242+0200 Runner [C11 FC362C8E-623E-4618-84B5-1DDB2A12E4CB play.googleapis.com:443 tcp, url hash: 7efd1bc6, tls, no cellular] start 09:29:46.023677+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C11] reporting state preparing 09:29:46.024286+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> setting up Connection 11 09:29:46.027738+0200 Runner tcp_output [C11.1:3] flags=[S] seq=1247171599, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=1247171599 09:29:46.028673+0200 Runner tcp_input [C9.1:3] flags=[F.] seq=3904254283, ack=590421483, win=255 state=FIN_WAIT_1 rcv_nxt=3904254283, snd_una=590421483 09:29:46.028793+0200 Runner tcp_output [C9.1:3] flags=[F.] seq=590421483, ack=3904254284, win=1024 state=CLOSING rcv_nxt=3904254284, snd_una=590421483 09:29:46.029608+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:46.029714+0200 Runner boringssl_context_new_session_handler(1118) [C10.1:2][0x14db35a30] New session available 09:29:46.029782+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:46.029871+0200 Runner boringssl_context_new_session_handler(1118) [C10.1:2][0x14db35a30] New session available 09:29:46.037622+0200 Runner tcp_input [C11.1:3] flags=[S.] seq=975394020, ack=1247171600, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=1247171599 09:29:46.037669+0200 Runner nw_flow_connected [C11.1 172.217.22.138:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:29:46.038022+0200 Runner boringssl_context_set_handshake_config(1472) [0x14db4d860] set tls_handshake_config_standard 09:29:46.038260+0200 Runner boringssl_context_set_min_version(326) [0x14db4d860] set 0x0301 09:29:46.038336+0200 Runner boringssl_context_set_max_version(310) [0x14db4d860] set 0x0304 09:29:46.038404+0200 Runner boringssl_context_set_cipher_suites(844) [0x14db4d860] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:29:46.038491+0200 Runner boringssl_context_set_remote_address(2556) [0x14db4d860] Saving remote IPv4 address 09:29:46.038523+0200 Runner boringssl_session_install_association_state(1289) [0x14db4d860] Client session cache miss 09:29:46.038590+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14db4d860] SNI play.googleapis.com 09:29:46.038647+0200 Runner boringssl_context_set_min_version(326) [C11.1:2][0x14db4d860] set 0x0303 09:29:46.038691+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C11.1:2][0x14db4d860] set false 09:29:46.038722+0200 Runner boringssl_context_set_false_start(411) [C11.1:2][0x14db4d860] set false 09:29:46.038804+0200 Runner boringssl_context_set_enforce_ev(401) [C11.1:2][0x14db4d860] set false 09:29:46.038846+0200 Runner boringssl_context_set_ats_enforced(1312) [C11.1:2][0x14db4d860] set false 09:29:46.038959+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C11.1:2][0x14db4d860] set 0 09:29:46.039048+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C11.1:2][0x14db4d860] set 0 09:29:46.039156+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C11.1:2][0x14db4d860] set 0 09:29:46.039232+0200 Runner boringssl_session_set_peer_hostname(1181) [C11.1:2][0x14db4d860] SNI play.googleapis.com 09:29:46.039526+0200 Runner boringssl_context_set_cipher_suites(844) [C11.1:2][0x14db4d860] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:29:46.039587+0200 Runner nw_protocol_boringssl_begin_connection(495) [C11.1:2][0x14db4d860] early data disabled 09:29:46.039625+0200 Runner boringssl_context_info_handler(1971) [C11.1:2][0x14db4d860] Client handshake started 09:29:46.039659+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Writing SSL3_RT_HANDSHAKE 512 bytes 09:29:46.039690+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS client enter_early_data 09:29:46.039736+0200 Runner boringssl_context_add_handshake_message_pending(579) [C11.1:2][0x14db4d860] Adding message(1) 09:29:46.039773+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS client read_server_hello 09:29:46.039803+0200 Runner boringssl_context_add_handshake_message_pending(579) [C11.1:2][0x14db4d860] Adding message(2) 09:29:46.039856+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.039910+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.039962+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.040043+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.042284+0200 Runner Task .<1> received response, status 200 content K 09:29:46.042418+0200 Runner Task .<1> done using Connection 10 09:29:46.042912+0200 Runner Task .<1> response ended 09:29:46.045679+0200 Runner Task .<1> summary for task success {transaction_duration_ms=118, response_status=200, connection=10, protocol="h2", domain_lookup_duration_ms=8, connect_duration_ms=75, secure_connection_duration_ms=50, request_start_ms=89, request_duration_ms=0, response_start_ms=117, response_duration_ms=0, request_bytes=350, response_bytes=1285, cache_hit=0} 09:29:46.045862+0200 Runner Task .<1> finished successfully 09:29:46.046348+0200 Runner removing all entries config 0x280ec1840 09:29:46.046888+0200 Runner removing all entries config 0x280ec1840 09:29:46.047712+0200 Runner Connection 10: is being canceled 09:29:46.047892+0200 Runner [C10 5F557076-857E-43E8-A3A0-1FEAFD3DAC4C www.googleapis.com:443 tcp, url hash: d47ec334, tls] cancel 09:29:46.048330+0200 Runner [C10 5F557076-857E-43E8-A3A0-1FEAFD3DAC4C www.googleapis.com:443 tcp, url hash: d47ec334, tls] cancelled [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 0.121s, DNS @0.000s took 0.008s, TCP @0.011s took 0.024s, TLS took 0.051s bytes in/out: 4920/1208, packets in/out: 9/10, rtt: 0.018s, retransmitted packets: 0, out-of-order packets: 0 09:29:46.048485+0200 Runner 0.000s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:29:46.048626+0200 Runner 0.000s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:29:46.048738+0200 Runner 0.000s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:29:46.048909+0200 Runner 0.008s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:29:46.049202+0200 Runner 0.009s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:29:46.049433+0200 Runner 0.009s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:29:46.049534+0200 Runner 0.009s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:29:46.049802+0200 Runner 0.010s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:29:46.049909+0200 Runner 0.011s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:29:46.050033+0200 Runner 0.030s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:29:46.050184+0200 Runner 0.035s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:29:46.050307+0200 Runner 0.035s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:29:46.050376+0200 Runner 0.086s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:29:46.050487+0200 Runner 0.086s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:29:46.050520+0200 Runner 0.086s [C10.1 B08741A8-59D0-4153-9250-2D71AAC38995 192.168.0.25:53754<->216.58.201.234:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:29:46.050578+0200 Runner 0.086s [C10 9769E5E9-BB5A-4EAA-A570-B8C94B56F267 www.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:29:46.050648+0200 Runner 0.121s [C10] path:cancel 09:29:46.051223+0200 Runner boringssl_context_message_handler(2259) [C10.1:2][0x14db35a30] Writing SSL3_RT_ALERT 2 bytes 09:29:46.051306+0200 Runner boringssl_context_handle_warning_alert(1894) [C10.1:2][0x14db35a30] write alert, level: warning, description: close notify 09:29:46.051369+0200 Runner boringssl_session_disconnect(539) [C10.1:2][0x14db35a30] SSL_shutdown 0 09:29:46.051568+0200 Runner nw_protocol_tcp_log_summary [C10.1:3] [16ECB7E4-C242-42FA-8ACD-1A84D1E86881 192.168.0.25:53754<->216.58.201.234:443] Init: 1, Conn_Time: 19.412ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: none, rtt_upd: 5, rtt: 18.500ms, rtt_var: 4.812ms rtt_nc: 18.500ms, rtt_var_nc: 4.812ms 09:29:46.051714+0200 Runner nw_flow_disconnected [C10.1 216.58.201.234:443 cancelled channel-flow ((null))] Output protocol disconnected 09:29:46.053898+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C10] reporting state cancelled 09:29:46.054007+0200 Runner Connection 10: destroyed 09:29:46.054121+0200 Runner tcp_output [C10.1:3] flags=[F.] seq=2656040148, ack=185459830, win=1024 state=FIN_WAIT_1 rcv_nxt=185459830, snd_una=2656040015 09:29:46.054219+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C10.1:2][0x14db35a30] nw_protocol_boringssl_remove_input_handler forced true 09:29:46.054274+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C10.1:2][0x14db35a30] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:29:46.057697+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.057811+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.063532+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 122 bytes 09:29:46.063745+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_hello_retry_request 09:29:46.063841+0200 Runner boringssl_context_add_handshake_message_pending(579) [C11.1:2][0x14db4d860] Adding message(2) 09:29:46.064126+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:29:46.064419+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_server_hello 09:29:46.064883+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:29:46.064985+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:29:46.065108+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 15 bytes 09:29:46.065249+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_certificate_request 09:29:46.065316+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 2634 bytes 09:29:46.065382+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_server_certificate 09:29:46.065491+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:29:46.065560+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 79 bytes 09:29:46.065675+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C11.1:2][0x14db4d860] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:46.065808+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C11.1:2][0x14db4d860] SecTrustCreateWithCertificates result: 0 09:29:46.065881+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C11.1:2][0x14db4d860] No TLS-provided OCSP response 09:29:46.065927+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C11.1:2][0x14db4d860] No TLS-provided SCTs 09:29:46.066082+0200 Runner boringssl_context_certificate_verify_callback(2072) [C11.1:2][0x14db4d860] Asyncing for verify block 09:29:46.066148+0200 Runner boringssl_session_handshake_incomplete(191) [C11.1:2][0x14db4d860] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:29:46.066193+0200 Runner Connection 11: asked to evaluate TLS Trust 09:29:46.073635+0200 Runner tcp_input [C10.1:3] flags=[F.] seq=185459830, ack=2656040148, win=248 state=FIN_WAIT_2 rcv_nxt=185459830, snd_una=2656040149 09:29:46.073702+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> auth completion disp=0 cred=0x280c94f80 09:29:46.079349+0200 Runner System Trust Evaluation yielded status(0) 09:29:46.079536+0200 Runner Connection 11: TLS Trust result 0 09:29:46.079670+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C11.1:2][0x14db4d860] Returning from verify block 09:29:46.079899+0200 Runner boringssl_context_certificate_verify_callback(2048) [C11.1:2][0x14db4d860] Setting trust result to ssl_verify_ok 09:29:46.079966+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client read_server_finished 09:29:46.080125+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 36 bytes 09:29:46.080265+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client send_end_of_early_data 09:29:46.080349+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client send_client_certificate 09:29:46.080396+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client complete_second_flight 09:29:46.080610+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Writing SSL3_RT_HANDSHAKE 36 bytes 09:29:46.080723+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS 1.3 client done 09:29:46.080825+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS client finish_client_handshake 09:29:46.080950+0200 Runner boringssl_context_info_handler(1984) [C11.1:2][0x14db4d860] Client handshake state: TLS client done 09:29:46.081182+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C11.1:2][0x14db4d860] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:29:46.081446+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C11.1:2][0x14db4d860] SecTrustCreateWithCertificates result: 0 09:29:46.081485+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C11.1:2][0x14db4d860] No TLS-provided OCSP response 09:29:46.081516+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C11.1:2][0x14db4d860] No TLS-provided SCTs 09:29:46.081623+0200 Runner boringssl_context_add_handshake_message_pending(579) [C11.1:2][0x14db4d860] Adding message(20) 09:29:46.081667+0200 Runner boringssl_context_info_handler(1975) [C11.1:2][0x14db4d860] Client handshake done 09:29:46.081696+0200 Runner nw_protocol_boringssl_signal_connected(699) [C11.1:2][0x14db4d860] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:29:46.081990+0200 Runner nw_flow_connected [C11.1 172.217.22.138:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:29:46.082299+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C11] reporting state ready 09:29:46.082465+0200 Runner Connection 11: connected successfully 09:29:46.082501+0200 Runner Connection 11: TLS handshake complete 09:29:46.082561+0200 Runner Connection 11: ready C(N) E(N) 09:29:46.082649+0200 Runner new connection to play.googleapis.com config 0x280e3cf80 09:29:46.082714+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> now using Connection 11 09:29:46.082750+0200 Runner Connection 11: received viability advisory(Y) 09:29:46.082798+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> sent request, body S 165 09:29:46.096673+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:46.096837+0200 Runner boringssl_context_new_session_handler(1118) [C11.1:2][0x14db4d860] New session available 09:29:46.096946+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Reading SSL3_RT_HANDSHAKE 248 bytes 09:29:46.097008+0200 Runner boringssl_context_new_session_handler(1118) [C11.1:2][0x14db4d860] New session available 09:29:46.107775+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> received response, status 200 content K 09:29:46.108248+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> done using Connection 11 09:29:46.108581+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> response ended 09:29:46.108746+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> summary for task success {transaction_duration_ms=96, response_status=200, connection=11, protocol="h2", domain_lookup_duration_ms=1, connect_duration_ms=62, secure_connection_duration_ms=40, request_start_ms=69, request_duration_ms=0, response_start_ms=96, response_duration_ms=0, request_bytes=318, response_bytes=509, cache_hit=0} 09:29:46.109448+0200 Runner Task <4E38AD16-C7DD-4138-B801-57F16E6E4B40>.<1> finished successfully 09:29:46.110276+0200 Runner removing all entries config 0x280e3cf80 09:29:46.110343+0200 Runner removing all entries config 0x280e3cf80 09:29:46.110450+0200 Runner Connection 11: is being canceled 09:29:46.110630+0200 Runner [C11 FC362C8E-623E-4618-84B5-1DDB2A12E4CB play.googleapis.com:443 tcp, url hash: 7efd1bc6, tls, no cellular] cancel 09:29:46.110735+0200 Runner [C11 FC362C8E-623E-4618-84B5-1DDB2A12E4CB play.googleapis.com:443 tcp, url hash: 7efd1bc6, tls, no cellular] cancelled [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 0.095s, DNS @0.000s took 0.001s, TCP @0.003s took 0.020s, TLS took 0.042s bytes in/out: 4144/1198, packets in/out: 9/11, rtt: 0.018s, retransmitted packets: 0, out-of-order packets: 0 09:29:46.110985+0200 Runner 0.000s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:29:46.111378+0200 Runner 0.000s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:29:46.111486+0200 Runner 0.000s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:29:46.111634+0200 Runner 0.001s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:29:46.111694+0200 Runner 0.002s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:29:46.111777+0200 Runner 0.002s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:29:46.111818+0200 Runner 0.002s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:29:46.111913+0200 Runner 0.003s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:29:46.111991+0200 Runner 0.003s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:29:46.112087+0200 Runner 0.023s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:29:46.112179+0200 Runner 0.023s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:29:46.112329+0200 Runner 0.065s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:29:46.112432+0200 Runner 0.065s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:29:46.112504+0200 Runner 0.065s [C11.1 499D2C54-EDB4-459D-AC58-AB9D8D66E777 192.168.0.25:53755<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:29:46.112740+0200 Runner 0.065s [C11 D52E2992-800F-4AC6-98F2-E1BDE619A878 play.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:29:46.113018+0200 Runner 0.095s [C11] path:cancel 09:29:46.114114+0200 Runner boringssl_context_message_handler(2259) [C11.1:2][0x14db4d860] Writing SSL3_RT_ALERT 2 bytes 09:29:46.114243+0200 Runner boringssl_context_handle_warning_alert(1894) [C11.1:2][0x14db4d860] write alert, level: warning, description: close notify 09:29:46.114489+0200 Runner boringssl_session_disconnect(539) [C11.1:2][0x14db4d860] SSL_shutdown 0 09:29:46.114729+0200 Runner nw_protocol_tcp_log_summary [C11.1:3] [C22C19AD-E4E3-43D4-82E9-71EC18376E12 192.168.0.25:53755<->172.217.22.138:443] Init: 1, Conn_Time: 19.292ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: process, rtt_upd: 4, rtt: 18.781ms, rtt_var: 2.562ms rtt_nc: 17.062ms, rtt_var_nc: 4.125ms 09:29:46.115617+0200 Runner nw_flow_disconnected [C11.1 172.217.22.138:443 cancelled channel-flow ((null))] Output protocol disconnected 09:29:46.116194+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C11] reporting state cancelled 09:29:46.116657+0200 Runner Connection 11: destroyed 09:29:46.116803+0200 Runner tcp_output [C11.1:3] flags=[F.] seq=1247172822, ack=975398165, win=1024 state=FIN_WAIT_1 rcv_nxt=975398165, snd_una=1247172689 09:29:46.116930+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C11.1:2][0x14db4d860] nw_protocol_boringssl_remove_input_handler forced true 09:29:46.117054+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C11.1:2][0x14db4d860] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:29:46.130050+0200 Runner tcp_input [C11.1:3] flags=[F.] seq=975398165, ack=1247172822, win=248 state=FIN_WAIT_1 rcv_nxt=975398165, snd_una=1247172798 09:29:46.130110+0200 Runner tcp_output [C11.1:3] flags=[F.] seq=1247172822, ack=975398166, win=1024 state=CLOSING rcv_nxt=975398166, snd_una=1247172822 09:30:01.384944+0200 Runner flutter: rebuilt tabs with user Instance of 'User' 09:30:01.411632+0200 Runner flutter: \^[[34m[WARNING] Easy Localization: Localization key [loading_downloaded_hikes] not found<…> 09:30:02.647344+0200 Runner flutter: rebuilt tabs with user Instance of 'User' 09:30:28.300383+0200 Runner flutter: _hasVibrator false 09:30:28.302340+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDistanceFilter:", "self":"0x280caad90", "distance":"1.000000"} 09:30:28.303056+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"distanceFilter", "old":"-1.000000", "new":"1.000000"} 09:30:28.304830+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setPausesLocationUpdatesAutomatically:", "self":"0x280caad90", "pausesLocationUpdatesAutomatically":0} 09:30:28.305192+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"pausesLocationUpdatesAutomatically", "old":{"type":"decode failure","raw value":0,"expected type":"Generic"}, "new":{"type":"decode failure","raw value":4,"expected type":"Generic"}} 09:30:28.309042+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:28.309104+0200 Runner 🔵-[TSLocationManager ready] 09:30:28.376336+0200 Runner flutter: [ready] {desiredAccuracy: -1, distanceFilter: 1.0, stationaryRadius: 25.0, locationTimeout: 60, disableElasticity: false, elasticityMultiplier: 1.0, stopAfterElapsedMinutes: -1, geofenceProximityRadius: 2000, geofenceInitialTriggerEntry: true, desiredOdometerAccuracy: 10.0, useSignificantChangesOnly: false, isMoving: true, stopTimeout: 1, activityRecognitionInterval: 10000, minimumActivityRecognitionConfidence: 70, disableStopDetection: true, stopOnStationary: false, url: , persistMode: 2, method: POST, httpRootProperty: location, params: {}, headers: {}, extras: {}, autoSync: true, disableAutoSyncOnCellular: false, autoSyncThreshold: 0, batchSync: false, maxBatchSize: -1, locationTemplate: , geofenceTemplate: , maxDaysToPersist: 1, maxRecordsToPersist: -1, locationsOrderDirection: ASC, httpTimeout: 60000, encrypt: false, authorization: {strategy: jwt, accessToken: null, refreshToken: null, refreshUrl: null, refreshPayload: null, expires: -1}, stopOnTerminate: true, startOnBoot: false, heartbeatInterval: 60, schedule: [], scheduleUseAlarmManager: false, debug: false, logLevel: 3, logMaxDays: 3, pausesLocationUpdatesAutomatically: false, locationAuthorizationRequest: Always, locationAuthorizationAlert: {settingsButton: Activer, cancelButton: Annuler, titleWhenNotEnabled: Votre GPS est désactivé, instructions: Le guidage ne pourra pas fonctionner correctement. Veuillez activer l'autorisation du GPS ''Toujours''., titleWhenOff: Votre GPS n'est pas toujours actif}, disableLocationAuthorizationAlert: false, showsBackgroundLocationIndicator: false, activityType: 1, stopDetectionDelay: 0, disableMotionActivityUpdates: false, preventSuspend: true, enableTimestampMeta: false} 09:30:28.378036+0200 Runner ℹ️-[TSLocationManager doStart:] trackingMode: 1 09:30:28.386734+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringSignificantLocationChanges", "self":"0x280caae60"} 09:30:28.386904+0200 Runner 🎾-[TSGeofenceManager start] 09:30:28.387598+0200 Runner nw_path_evaluator_start [917C2DF0-D942-4AB1-80FA-D9A5C3B2A5D2 generic, indefinite] path: satisfied (Path is satisfied), interface: en0, ipv4, dns 09:30:28.421727+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"location", "self":"0x280caad90"} 09:30:28.422056+0200 Runner {"msg":"Sending cached messages to daemon", "event":"activity"} 09:30:28.422135+0200 Runner 🎾-[SOMotionDetector startDetection] 09:30:28.423938+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDistanceFilter:", "self":"0x280caad90", "distance":"-1.000000"} 09:30:28.442060+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"distanceFilter", "old":"1.000000", "new":"-1.000000"} 09:30:28.442367+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDesiredAccuracy:", "self":"0x280caad90", "accuracy":"-2.000000"} 09:30:28.442578+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"desiredAccuracy", "old":"-1.000000", "new":"-2.000000"} 09:30:28.442821+0200 Runner 🔵-[TSLocationManager setPace:] 1 09:30:28.442925+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setPausesLocationUpdatesAutomatically:", "self":"0x280caad90", "pausesLocationUpdatesAutomatically":0} 09:30:28.443042+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"pausesLocationUpdatesAutomatically", "old":{"type":"decode failure","raw value":4,"expected type":"Generic"}, "new":{"type":"decode failure","raw value":4,"expected type":"Generic"}} 09:30:28.443425+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setAllowsBackgroundLocationUpdates:", "self":"0x280caad90", "allows":1} 09:30:28.443537+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"allowsBackgroundLocationUpdates", "old":0, "new":1} 09:30:28.444003+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setShowsBackgroundLocationIndicator:", "self":"0x280caad90", "shows":0} 09:30:28.444117+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"showsBackgroundLocationIndicator", "old":0, "new":0} 09:30:28.444390+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:28.444535+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"updatingLocation", "old":0, "new":1} 09:30:28.444750+0200 Runner 🎾-[TSLocationManager startUpdatingLocation] Location-services: ON 09:30:28.446835+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:28.447051+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"requestAlwaysAuthorization", "self":"0x280caad90", "SEL":"requestAlwaysAuthorization"} 09:30:28.447125+0200 Runner {"msg":"Requesting always authorization"} 09:30:28.448268+0200 Runner ℹ️+[LocationAuthorization run:onCancel:] status: 0 09:30:28.450546+0200 Runner 🔵+[LocationAuthorization run:onCancel:] Request: requestAlwaysAuthorization 09:30:28.450809+0200 Runner 🎾-[TSLocationManager startMonitoringBackgroundFetch] BackgroundFetch: ON 09:30:28.502577+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:30:28.502656+0200 Runner submitTaskRequest: 09:30:28.502747+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:519B19 09:30:28.502830+0200 Runner Establish daemon connection; interrupted: 0 09:30:28.644092+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:28.702435+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"location", "self":"0x280caad90"} 09:30:28.702507+0200 Runner 🔵-[TSLocationManager changePace:] isMoving: 1 09:30:28.702682+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDistanceFilter:", "self":"0x280caad90", "distance":"-1.000000"} 09:30:28.702841+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"distanceFilter", "old":"-1.000000", "new":"-1.000000"} 09:30:28.702984+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:28.703161+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDesiredAccuracy:", "self":"0x280caad90", "accuracy":"-2.000000"} 09:30:28.703298+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"desiredAccuracy", "old":"-2.000000", "new":"-2.000000"} 09:30:28.703444+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:28.703664+0200 Runner 🔵-[TSLocationManager setPace:] 1 09:30:28.703870+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setPausesLocationUpdatesAutomatically:", "self":"0x280caad90", "pausesLocationUpdatesAutomatically":0} 09:30:28.704020+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"pausesLocationUpdatesAutomatically", "old":{"type":"decode failure","raw value":4,"expected type":"Generic"}, "new":{"type":"decode failure","raw value":4,"expected type":"Generic"}} 09:30:28.704457+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setAllowsBackgroundLocationUpdates:", "self":"0x280caad90", "allows":1} 09:30:28.704718+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"allowsBackgroundLocationUpdates", "old":1, "new":1} 09:30:28.704973+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setShowsBackgroundLocationIndicator:", "self":"0x280caad90", "shows":0} 09:30:28.705061+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"showsBackgroundLocationIndicator", "old":0, "new":0} 09:30:28.705188+0200 Runner 🎾-[TSLocationManager startUpdatingLocation] Location-services: ON 09:30:28.705545+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:28.710279+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:28.710570+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"requestAlwaysAuthorization", "self":"0x280caad90", "SEL":"requestAlwaysAuthorization"} 09:30:28.710602+0200 Runner {"msg":"Requesting always authorization"} 09:30:28.711911+0200 Runner ℹ️+[LocationAuthorization run:onCancel:] status: 0 09:30:28.711961+0200 Runner 🔵+[LocationAuthorization run:onCancel:] Request: requestAlwaysAuthorization 09:30:28.769759+0200 Runner flutter: {TrackingService} {startTracking} {start tracking hike: Test mangin #2850} {LogLevel.WARNING} {15 juin 2020 09:30:28 AM} 09:30:28.855456+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> resuming, QOS(0x9) Voucher (null) 09:30:28.856364+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:30:28.856848+0200 Runner Connection 12: enabling TLS 09:30:28.856928+0200 Runner Connection 12: starting, TC(0x0) 09:30:28.857079+0200 Runner [C12 6F95398A-A903-4F57-9534-EF4BCFFF3D31 app-measurement.com:443 tcp, url hash: 69ae1ed7, tls] start 09:30:28.858618+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C12] reporting state preparing 09:30:28.859788+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> setting up Connection 12 09:30:28.863605+0200 Runner tcp_output [C12.1:3] flags=[S] seq=2508241553, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=2508241553 09:30:28.887219+0200 Runner tcp_input [C12.1:3] flags=[S.] seq=3464884432, ack=2508241554, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=2508241553 09:30:28.887953+0200 Runner nw_flow_connected [C12.1 216.58.215.46:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:30:28.888290+0200 Runner boringssl_context_set_handshake_config(1472) [0x14db95eb0] set tls_handshake_config_standard 09:30:28.888374+0200 Runner boringssl_context_set_min_version(326) [0x14db95eb0] set 0x0301 09:30:28.888428+0200 Runner boringssl_context_set_max_version(310) [0x14db95eb0] set 0x0304 09:30:28.888501+0200 Runner boringssl_context_set_cipher_suites(844) [0x14db95eb0] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:30:28.888540+0200 Runner boringssl_context_set_remote_address(2556) [0x14db95eb0] Saving remote IPv4 address 09:30:28.888611+0200 Runner boringssl_session_install_association_state(1289) [0x14db95eb0] Client session cache miss 09:30:28.888689+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14db95eb0] SNI app-measurement.com 09:30:28.888859+0200 Runner boringssl_context_set_min_version(326) [C12.1:2][0x14db95eb0] set 0x0303 09:30:28.888942+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C12.1:2][0x14db95eb0] set false 09:30:28.889018+0200 Runner boringssl_context_set_false_start(411) [C12.1:2][0x14db95eb0] set false 09:30:28.889087+0200 Runner boringssl_context_set_enforce_ev(401) [C12.1:2][0x14db95eb0] set false 09:30:28.889148+0200 Runner boringssl_context_set_ats_enforced(1312) [C12.1:2][0x14db95eb0] set false 09:30:28.889199+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C12.1:2][0x14db95eb0] set 0 09:30:28.889244+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C12.1:2][0x14db95eb0] set 0 09:30:28.889301+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C12.1:2][0x14db95eb0] set 0 09:30:28.889368+0200 Runner boringssl_session_set_peer_hostname(1181) [C12.1:2][0x14db95eb0] SNI app-measurement.com 09:30:28.889419+0200 Runner boringssl_context_set_cipher_suites(844) [C12.1:2][0x14db95eb0] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:30:28.889458+0200 Runner nw_protocol_boringssl_begin_connection(495) [C12.1:2][0x14db95eb0] early data disabled 09:30:28.889491+0200 Runner boringssl_context_info_handler(1971) [C12.1:2][0x14db95eb0] Client handshake started 09:30:28.889537+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Writing SSL3_RT_HANDSHAKE 512 bytes 09:30:28.889575+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS client enter_early_data 09:30:28.889609+0200 Runner boringssl_context_add_handshake_message_pending(579) [C12.1:2][0x14db95eb0] Adding message(1) 09:30:28.889653+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS client read_server_hello 09:30:28.889693+0200 Runner boringssl_context_add_handshake_message_pending(579) [C12.1:2][0x14db95eb0] Adding message(2) 09:30:28.889786+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:28.889889+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:28.889936+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:28.889995+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:28.915653+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:28.915800+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:28.921169+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 122 bytes 09:30:28.921330+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_hello_retry_request 09:30:28.921639+0200 Runner boringssl_context_add_handshake_message_pending(579) [C12.1:2][0x14db95eb0] Adding message(2) 09:30:28.921717+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:30:28.921895+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_server_hello 09:30:28.922072+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:30:28.922162+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 15 bytes 09:30:28.922250+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_certificate_request 09:30:28.922395+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 2611 bytes 09:30:28.922555+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_server_certificate 09:30:28.922632+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:30:28.922702+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 80 bytes 09:30:28.922777+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C12.1:2][0x14db95eb0] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:30:28.922991+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C12.1:2][0x14db95eb0] SecTrustCreateWithCertificates result: 0 09:30:28.923063+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C12.1:2][0x14db95eb0] No TLS-provided OCSP response 09:30:28.923306+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C12.1:2][0x14db95eb0] No TLS-provided SCTs 09:30:28.923449+0200 Runner boringssl_context_certificate_verify_callback(2072) [C12.1:2][0x14db95eb0] Asyncing for verify block 09:30:28.923532+0200 Runner boringssl_session_handshake_incomplete(191) [C12.1:2][0x14db95eb0] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:30:28.923628+0200 Runner Connection 12: asked to evaluate TLS Trust 09:30:28.928129+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> auth completion disp=0 cred=0x280c86ee0 09:30:28.938903+0200 Runner System Trust Evaluation yielded status(0) 09:30:28.938986+0200 Runner Connection 12: TLS Trust result 0 09:30:28.939280+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C12.1:2][0x14db95eb0] Returning from verify block 09:30:28.939477+0200 Runner boringssl_context_certificate_verify_callback(2048) [C12.1:2][0x14db95eb0] Setting trust result to ssl_verify_ok 09:30:28.939554+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client read_server_finished 09:30:28.939670+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 36 bytes 09:30:28.939734+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client send_end_of_early_data 09:30:28.939785+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client send_client_certificate 09:30:28.939847+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client complete_second_flight 09:30:28.939902+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Writing SSL3_RT_HANDSHAKE 36 bytes 09:30:28.939963+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS 1.3 client done 09:30:28.940027+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS client finish_client_handshake 09:30:28.940087+0200 Runner boringssl_context_info_handler(1984) [C12.1:2][0x14db95eb0] Client handshake state: TLS client done 09:30:28.940139+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C12.1:2][0x14db95eb0] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:30:28.940253+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C12.1:2][0x14db95eb0] SecTrustCreateWithCertificates result: 0 09:30:28.940311+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C12.1:2][0x14db95eb0] No TLS-provided OCSP response 09:30:28.940387+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C12.1:2][0x14db95eb0] No TLS-provided SCTs 09:30:28.940551+0200 Runner boringssl_context_add_handshake_message_pending(579) [C12.1:2][0x14db95eb0] Adding message(20) 09:30:28.940660+0200 Runner boringssl_context_info_handler(1975) [C12.1:2][0x14db95eb0] Client handshake done 09:30:28.940737+0200 Runner nw_protocol_boringssl_signal_connected(699) [C12.1:2][0x14db95eb0] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:30:28.940927+0200 Runner nw_flow_connected [C12.1 216.58.215.46:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:30:28.941317+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C12] reporting state ready 09:30:28.941632+0200 Runner Connection 12: connected successfully 09:30:28.941680+0200 Runner Connection 12: TLS handshake complete 09:30:28.941740+0200 Runner Connection 12: ready C(N) E(N) 09:30:28.941869+0200 Runner new connection to app-measurement.com config 0x280e00940 09:30:28.941917+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> now using Connection 12 09:30:28.941973+0200 Runner Connection 12: received viability advisory(Y) 09:30:28.942007+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> sent request, body S 471 09:30:28.965852+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 248 bytes 09:30:28.965961+0200 Runner boringssl_context_new_session_handler(1118) [C12.1:2][0x14db95eb0] New session available 09:30:28.965995+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Reading SSL3_RT_HANDSHAKE 248 bytes 09:30:28.966063+0200 Runner boringssl_context_new_session_handler(1118) [C12.1:2][0x14db95eb0] New session available 09:30:28.974096+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> received response, status 204 content U 09:30:28.974147+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> done using Connection 12 09:30:28.974328+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> response ended 09:30:28.977742+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> summary for task success {transaction_duration_ms=118, response_status=204, connection=12, protocol="h2", domain_lookup_duration_ms=1, connect_duration_ms=75, secure_connection_duration_ms=47, request_start_ms=82, request_duration_ms=0, response_start_ms=117, response_duration_ms=0, request_bytes=610, response_bytes=322, cache_hit=0} 09:30:28.977915+0200 Runner Task <11BDCDD7-2A1A-4481-BD2F-7BC0978EA257>.<1> finished successfully 09:30:28.978019+0200 Runner removing all entries config 0x280e00940 09:30:28.978108+0200 Runner Connection 12: is being canceled 09:30:28.978194+0200 Runner [C12 6F95398A-A903-4F57-9534-EF4BCFFF3D31 app-measurement.com:443 tcp, url hash: 69ae1ed7, tls] cancel 09:30:28.978245+0200 Runner [C12 6F95398A-A903-4F57-9534-EF4BCFFF3D31 app-measurement.com:443 tcp, url hash: 69ae1ed7, tls] cancelled [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 0.118s, DNS @0.000s took 0.001s, TCP @0.004s took 0.026s, TLS took 0.049s bytes in/out: 3869/1468, packets in/out: 6/10, rtt: 0.028s, retransmitted packets: 0, out-of-order packets: 0 09:30:28.978358+0200 Runner 0.000s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:30:28.978478+0200 Runner 0.000s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:30:28.978534+0200 Runner 0.000s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:30:28.978600+0200 Runner 0.001s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:30:28.978692+0200 Runner 0.002s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:30:28.978764+0200 Runner 0.002s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:30:28.978869+0200 Runner 0.002s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:30:28.978910+0200 Runner 0.003s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:30:28.979028+0200 Runner 0.004s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:30:28.979139+0200 Runner 0.030s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:30:28.979268+0200 Runner 0.030s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:30:28.979341+0200 Runner 0.079s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:30:28.979373+0200 Runner 0.079s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:30:28.979423+0200 Runner 0.079s [C12.1 D8B93F58-62B7-4C56-8EA8-8718FE11AAA6 192.168.0.25:53756<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:30:28.979454+0200 Runner 0.079s [C12 74A9294D-F9C3-4CDB-AB04-289E5B8E2310 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:30:28.979515+0200 Runner 0.118s [C12] path:cancel 09:30:28.980074+0200 Runner boringssl_context_message_handler(2259) [C12.1:2][0x14db95eb0] Writing SSL3_RT_ALERT 2 bytes 09:30:28.980115+0200 Runner boringssl_context_handle_warning_alert(1894) [C12.1:2][0x14db95eb0] write alert, level: warning, description: close notify 09:30:28.980170+0200 Runner boringssl_session_disconnect(539) [C12.1:2][0x14db95eb0] SSL_shutdown 0 09:30:28.980721+0200 Runner nw_protocol_tcp_log_summary [C12.1:3] [C5DA998D-43CD-4C77-A44F-BCD5BFB0C198 192.168.0.25:53756<->216.58.215.46:443] Init: 1, Conn_Time: 25.978ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: kernel, rtt_upd: 4, rtt: 28.062ms, rtt_var: 4.250ms rtt_nc: 26.468ms, rtt_var_nc: 6.250ms 09:30:28.981161+0200 Runner nw_flow_disconnected [C12.1 216.58.215.46:443 cancelled channel-flow ((null))] Output protocol disconnected 09:30:28.981321+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C12] reporting state cancelled 09:30:28.981489+0200 Runner removing all entries config 0x280e00940 09:30:28.981686+0200 Runner tcp_output [C12.1:3] flags=[F.] seq=2508243046, ack=3464888302, win=1024 state=FIN_WAIT_1 rcv_nxt=3464888302, snd_una=2508242913 09:30:28.981762+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C12.1:2][0x14db95eb0] nw_protocol_boringssl_remove_input_handler forced true 09:30:28.981831+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C12.1:2][0x14db95eb0] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:30:29.003990+0200 Runner tcp_input [C12.1:3] flags=[F.] seq=3464888302, ack=2508243046, win=248 state=FIN_WAIT_1 rcv_nxt=3464888302, snd_una=2508243046 09:30:29.004236+0200 Runner tcp_output [C12.1:3] flags=[F.] seq=2508243046, ack=3464888303, win=1024 state=CLOSING rcv_nxt=3464888303, snd_una=2508243046 09:30:30.593151+0200 Runner ✅-[SOMotionDetector startDetection]_block_invoke Enabled M7 MotionActivity updates 09:30:32.504602+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca6a70", "property":"previousAuthorizationStatus", "old":"NotDetermined", "new":"AuthorizedAlways"} 09:30:32.504734+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca6a70", "property":"previousAuthorizationStatusValid", "old":1, "new":1} 09:30:32.504791+0200 Runner 🔵-[BackgroundTaskManager locationManager:didChangeAuthorizationStatus:] 3 09:30:32.508095+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caae60", "property":"previousAuthorizationStatus", "old":"NotDetermined", "new":"AuthorizedAlways"} 09:30:32.508539+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caae60", "property":"previousAuthorizationStatusValid", "old":1, "new":1} 09:30:32.516530+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca68b0", "property":"previousAuthorizationStatus", "old":"NotDetermined", "new":"AuthorizedAlways"} 09:30:32.516732+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca68b0", "property":"previousAuthorizationStatusValid", "old":1, "new":1} 09:30:32.516815+0200 Runner 🔵-[LocationManager locationManager:didChangeAuthorizationStatus:] 3 09:30:32.545280+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"previousAuthorizationStatus", "old":"NotDetermined", "new":"AuthorizedAlways"} 09:30:32.545361+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"previousAuthorizationStatusValid", "old":1, "new":1} 09:30:32.546499+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:32.546566+0200 Runner 🔵-[TSLocationManager locationManager:didChangeAuthorizationStatus:] status 3 09:30:32.572712+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caadc0", "property":"previousAuthorizationStatus", "old":"NotDetermined", "new":"AuthorizedAlways"} 09:30:32.572796+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caadc0", "property":"previousAuthorizationStatusValid", "old":1, "new":1} 09:30:32.572959+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca6a60", "property":"previousAuthorizationStatus", "old":"NotDetermined", "new":"AuthorizedAlways"} 09:30:32.573189+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca6a60", "property":"previousAuthorizationStatusValid", "old":1, "new":1} 09:30:32.573652+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:32.574510+0200 Runner 🔵-[LocationManager locationManager:didChangeAuthorizationStatus:] 3 09:30:32.574599+0200 Runner 🔵-[LocationManager locationManager:didChangeAuthorizationStatus:] 3 09:30:32.583662+0200 Runner ℹ️+[LocationAuthorization run:onCancel:] status: 3 09:30:32.583714+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:32.583853+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:32.583940+0200 Runner ℹ️-[TSLocationManager locationManager:didUpdateLocations:] Received stale motionchange location. Retrying... 09:30:32.594687+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:32.594741+0200 Runner ℹ️-[TSLocationManager locationManager:didUpdateLocations:] Received stale motionchange location. Retrying... 09:30:32.877424+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:32.878010+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDesiredAccuracy:", "self":"0x280caad90", "accuracy":"-1.000000"} 09:30:32.878103+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"desiredAccuracy", "old":"-2.000000", "new":"-1.000000"} 09:30:32.878221+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:32.878268+0200 Runner ✅-[TSLocationManager locationManager:didUpdateLocations:] Acquired motionchange position: <+45.17973272,+5.71995173> +/- 65.00m (speed -1.00 mps / course -1.00) @ 15/06/2020 09:27:18 heure d’été d’Europe centrale 09:30:32.878740+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setDistanceFilter:", "self":"0x280caad90", "distance":"1.000000"} 09:30:32.878828+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"distanceFilter", "old":"-1.000000", "new":"1.000000"} 09:30:32.878935+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:32.879211+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setAllowsBackgroundLocationUpdates:", "self":"0x280caad90", "allows":1} 09:30:32.879282+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"allowsBackgroundLocationUpdates", "old":1, "new":1} 09:30:32.879565+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"setShowsBackgroundLocationIndicator:", "self":"0x280caad90", "shows":0} 09:30:32.879612+0200 Runner 🎾-[TSLocationManager startUpdatingLocation] Location-services: ON 09:30:32.879677+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"showsBackgroundLocationIndicator", "old":0, "new":0} 09:30:32.879877+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caad90"} 09:30:32.880739+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:32.881206+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringSignificantLocationChanges", "self":"0x280caad90"} 09:30:32.881525+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:32.881661+0200 Runner 🎾-[TSLocationManager startMonitoringSignificantLocationChanges] 09:30:33.009155+0200 Runner ℹ️+[LocationAuthorization run:onCancel:] status: 3 09:30:33.009299+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 0D644B90-1F2B-40F6-A571-5B206A604652 09:30:33.009501+0200 Runner New location predates old location, and the event types differ; discarding 09:30:33.012923+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.179732719642466,5.719951726409275, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:32 AM} 09:30:33.016177+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:32 AM} 09:30:33.016264+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:32 AM} 09:30:33.040694+0200 Runner HTHangEventCreate: HangTracing is disabled. Not creating a new event. 09:30:33.041646+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280caad90", "notification":"NSConcreteNotification 0x2801fb810 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:30:33.041821+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280caadc0", "notification":"NSConcreteNotification 0x2801fb810 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:30:33.042006+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280caae60", "notification":"NSConcreteNotification 0x2801fb810 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:30:33.042156+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280ca68b0", "notification":"NSConcreteNotification 0x2801fb810 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:30:33.042335+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280ca6a70", "notification":"NSConcreteNotification 0x2801fb810 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:30:33.042500+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280ca6a60", "notification":"NSConcreteNotification 0x2801fb810 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:30:33.052486+0200 Runner Read options: 0 -- URL: file:///var/mobile/Containers/Data/Application/3D934029-4918-44EA-A0C2-588F4808DF33/Library/Application%20Support/Google/FIRApp/FIREBASE_DIAGNOSTICS_HEARTBEAT_DATE -- purposeID: A8A14FF0-DD9B-4FA0-B687-F0E86244A742 -- claimID: 4FB5D773-A0AF-4BBB-8493-AE91CE5C3EA4 09:30:33.060337+0200 Runner Claim 4FB5D773-A0AF-4BBB-8493-AE91CE5C3EA4 granted in client 09:30:33.060414+0200 Runner Claim 4FB5D773-A0AF-4BBB-8493-AE91CE5C3EA4 invoked in client 09:30:33.543884+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:33.544025+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:33.544554+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:33.547501+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17948569,+5.71997893>, radius:150.00m)"} 09:30:33.549582+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:34.713382+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:34.713553+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:34.713732+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:34.713923+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17952297,+5.71996397>, radius:150.00m)"} 09:30:34.788357+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:34.788508+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 8A3EA59A-95A4-45DF-95C9-A44E6C6354B1 09:30:34.808958+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:34.809187+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:34.809376+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:34.809576+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:34.809734+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:34.810326+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:34.812597+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.17952297069139,5.719963968027158, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:34 AM} 09:30:34.862840+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:34 AM} 09:30:34.862915+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:34 AM} 09:30:35.696604+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:35.696732+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:35.696856+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:35.697464+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17953901,+5.71995755>, radius:150.00m)"} 09:30:35.718897+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:35.719017+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 494DB12E-040D-4A04-8FC9-2EF5E3E2D16D 09:30:35.726641+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:35.726730+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:35.726876+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:35.726989+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:35.729247+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:35.731545+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:35.731594+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.179539013703454,5.719957551034145, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:35 AM} 09:30:35.797224+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:35 AM} 09:30:35.797426+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:35 AM} 09:30:39.753314+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:39.753492+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:39.753659+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:39.757685+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17962929,+5.71998119>, radius:150.00m)"} 09:30:39.759639+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:39.792752+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 218E4225-64C6-4BFE-80F5-BA45621A2AC0 09:30:39.801580+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:39.801753+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:39.801888+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:39.802022+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:39.802623+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:39.802746+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:39.802786+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.17962928525785,5.719981186219018, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:39 AM} 09:30:39.809588+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:39 AM} 09:30:39.809638+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:39 AM} 09:30:40.841284+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:40.841387+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:40.841485+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:40.841645+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17965237,+5.71999151>, radius:150.00m)"} 09:30:40.843497+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:40.881838+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 1E70CE0C-ED6E-44E7-8CD8-3F5E2FBB9794 09:30:40.919367+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:40.919658+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:40.919950+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:40.920156+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:40.920872+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:40.921077+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:40.924730+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.179652370941696,5.719991514596968, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:40 AM} 09:30:40.942688+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:40 AM} 09:30:40.942778+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:40 AM} 09:30:41.736082+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:41.736294+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:41.736465+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:41.736682+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17966176,+5.71999622>, radius:150.00m)"} 09:30:41.738984+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:41.767633+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 8B92B4D0-E1D6-4D34-BB73-CBE6220D59A0 09:30:41.775541+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:41.775651+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:41.775791+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:41.775933+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:41.776420+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:41.776555+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:41.780061+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.17966175914912,5.719996220569451, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:41 AM} 09:30:41.780849+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:41 AM} 09:30:41.780920+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:41 AM} 09:30:44.024126+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopUpdatingLocation", "self":"0x280ca6a70"} 09:30:44.024251+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280ca6a70", "property":"updatingLocation", "old":0, "new":0} 09:30:44.031337+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:44.070633+0200 Runner ✅-[BackgroundTaskManager createBackgroundTask] 54 09:30:44.073046+0200 Runner 🔵-[TSLocationManager onSuspend:] enabled? 1) 09:30:44.254586+0200 Runner Can't end BackgroundTask: no background task exists with identifier 47 (0x2f), or it may have already been ended. Break in UIApplicationEndBackgroundTaskError() to debug. 09:30:45.104815+0200 Runner Task .<1> resuming, QOS(0x9) Voucher 09:30:45.107623+0200 Runner Task .<1> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:30:45.108102+0200 Runner Connection 13: enabling TLS 09:30:45.108139+0200 Runner Connection 13: starting, TC(0x0) 09:30:45.108386+0200 Runner [C13 813E80DF-5F82-4040-8DB3-1A704A639534 app-measurement.com:443 tcp, url hash: 69ae1ed7, tls] start 09:30:45.110100+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C13] reporting state preparing 09:30:45.111684+0200 Runner Task .<1> setting up Connection 13 09:30:45.118302+0200 Runner tcp_output [C13.1:3] flags=[S] seq=3086236713, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=3086236713 09:30:45.139056+0200 Runner tcp_input [C13.1:3] flags=[S.] seq=883569245, ack=3086236714, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=3086236713 09:30:45.139378+0200 Runner nw_flow_connected [C13.1 216.58.215.46:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:30:45.140188+0200 Runner boringssl_context_set_handshake_config(1472) [0x14bd63d60] set tls_handshake_config_standard 09:30:45.140398+0200 Runner boringssl_context_set_min_version(326) [0x14bd63d60] set 0x0301 09:30:45.140440+0200 Runner boringssl_context_set_max_version(310) [0x14bd63d60] set 0x0304 09:30:45.140851+0200 Runner boringssl_context_set_cipher_suites(844) [0x14bd63d60] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:30:45.141139+0200 Runner boringssl_context_set_remote_address(2556) [0x14bd63d60] Saving remote IPv4 address 09:30:45.141442+0200 Runner boringssl_session_install_association_state(1289) [0x14bd63d60] Client session cache miss 09:30:45.141489+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14bd63d60] SNI app-measurement.com 09:30:45.141524+0200 Runner boringssl_context_set_min_version(326) [C13.1:2][0x14bd63d60] set 0x0303 09:30:45.141592+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C13.1:2][0x14bd63d60] set false 09:30:45.141701+0200 Runner boringssl_context_set_false_start(411) [C13.1:2][0x14bd63d60] set false 09:30:45.142010+0200 Runner boringssl_context_set_enforce_ev(401) [C13.1:2][0x14bd63d60] set false 09:30:45.142059+0200 Runner boringssl_context_set_ats_enforced(1312) [C13.1:2][0x14bd63d60] set false 09:30:45.142129+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C13.1:2][0x14bd63d60] set 0 09:30:45.142184+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C13.1:2][0x14bd63d60] set 0 09:30:45.142220+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C13.1:2][0x14bd63d60] set 0 09:30:45.142538+0200 Runner boringssl_session_set_peer_hostname(1181) [C13.1:2][0x14bd63d60] SNI app-measurement.com 09:30:45.142600+0200 Runner boringssl_context_set_cipher_suites(844) [C13.1:2][0x14bd63d60] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:30:45.142651+0200 Runner nw_protocol_boringssl_begin_connection(495) [C13.1:2][0x14bd63d60] early data disabled 09:30:45.142709+0200 Runner boringssl_context_info_handler(1971) [C13.1:2][0x14bd63d60] Client handshake started 09:30:45.143115+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Writing SSL3_RT_HANDSHAKE 512 bytes 09:30:45.143183+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS client enter_early_data 09:30:45.143358+0200 Runner boringssl_context_add_handshake_message_pending(579) [C13.1:2][0x14bd63d60] Adding message(1) 09:30:45.143560+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS client read_server_hello 09:30:45.143676+0200 Runner boringssl_context_add_handshake_message_pending(579) [C13.1:2][0x14bd63d60] Adding message(2) 09:30:45.143771+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:45.143950+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:45.144167+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:45.144218+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:45.164586+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:45.164693+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:30:45.169246+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 122 bytes 09:30:45.169307+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_hello_retry_request 09:30:45.169401+0200 Runner boringssl_context_add_handshake_message_pending(579) [C13.1:2][0x14bd63d60] Adding message(2) 09:30:45.169436+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:30:45.169490+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_server_hello 09:30:45.170866+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:30:45.170902+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 15 bytes 09:30:45.170975+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_certificate_request 09:30:45.171056+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 2611 bytes 09:30:45.171279+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_server_certificate 09:30:45.171315+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:30:45.171416+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 78 bytes 09:30:45.171664+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C13.1:2][0x14bd63d60] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:30:45.172018+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C13.1:2][0x14bd63d60] SecTrustCreateWithCertificates result: 0 09:30:45.172174+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C13.1:2][0x14bd63d60] No TLS-provided OCSP response 09:30:45.172208+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C13.1:2][0x14bd63d60] No TLS-provided SCTs 09:30:45.172482+0200 Runner boringssl_context_certificate_verify_callback(2072) [C13.1:2][0x14bd63d60] Asyncing for verify block 09:30:45.172753+0200 Runner boringssl_session_handshake_incomplete(191) [C13.1:2][0x14bd63d60] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:30:45.172794+0200 Runner Connection 13: asked to evaluate TLS Trust 09:30:45.189312+0200 Runner Task .<1> auth completion disp=0 cred=0x280c82590 09:30:45.198331+0200 Runner System Trust Evaluation yielded status(0) 09:30:45.198399+0200 Runner Connection 13: TLS Trust result 0 09:30:45.198718+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C13.1:2][0x14bd63d60] Returning from verify block 09:30:45.199045+0200 Runner boringssl_context_certificate_verify_callback(2048) [C13.1:2][0x14bd63d60] Setting trust result to ssl_verify_ok 09:30:45.199778+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client read_server_finished 09:30:45.199882+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 36 bytes 09:30:45.200088+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client send_end_of_early_data 09:30:45.200330+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client send_client_certificate 09:30:45.200366+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client complete_second_flight 09:30:45.200572+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Writing SSL3_RT_HANDSHAKE 36 bytes 09:30:45.200793+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS 1.3 client done 09:30:45.201143+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS client finish_client_handshake 09:30:45.201436+0200 Runner boringssl_context_info_handler(1984) [C13.1:2][0x14bd63d60] Client handshake state: TLS client done 09:30:45.201534+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C13.1:2][0x14bd63d60] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:30:45.201961+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C13.1:2][0x14bd63d60] SecTrustCreateWithCertificates result: 0 09:30:45.202256+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C13.1:2][0x14bd63d60] No TLS-provided OCSP response 09:30:45.202325+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C13.1:2][0x14bd63d60] No TLS-provided SCTs 09:30:45.202653+0200 Runner boringssl_context_add_handshake_message_pending(579) [C13.1:2][0x14bd63d60] Adding message(20) 09:30:45.202697+0200 Runner boringssl_context_info_handler(1975) [C13.1:2][0x14bd63d60] Client handshake done 09:30:45.202874+0200 Runner nw_protocol_boringssl_signal_connected(699) [C13.1:2][0x14bd63d60] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:30:45.203444+0200 Runner nw_flow_connected [C13.1 216.58.215.46:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:30:45.204797+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C13] reporting state ready 09:30:45.205892+0200 Runner Connection 13: connected successfully 09:30:45.206047+0200 Runner Connection 13: TLS handshake complete 09:30:45.207819+0200 Runner Connection 13: ready C(N) E(N) 09:30:45.208409+0200 Runner new connection to app-measurement.com config 0x280e80580 09:30:45.208908+0200 Runner Task .<1> now using Connection 13 09:30:45.208965+0200 Runner Connection 13: received viability advisory(Y) 09:30:45.209743+0200 Runner Task .<1> sent request, body S 473 09:30:45.220737+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 248 bytes 09:30:45.220869+0200 Runner boringssl_context_new_session_handler(1118) [C13.1:2][0x14bd63d60] New session available 09:30:45.220982+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Reading SSL3_RT_HANDSHAKE 248 bytes 09:30:45.221064+0200 Runner boringssl_context_new_session_handler(1118) [C13.1:2][0x14bd63d60] New session available 09:30:45.240185+0200 Runner Task .<1> received response, status 204 content U 09:30:45.240370+0200 Runner Task .<1> done using Connection 13 09:30:45.240848+0200 Runner Task .<1> response ended 09:30:45.241266+0200 Runner Task .<1> summary for task success {transaction_duration_ms=134, response_status=204, connection=13, protocol="h2", domain_lookup_duration_ms=3, connect_duration_ms=87, secure_connection_duration_ms=60, request_start_ms=103, request_duration_ms=0, response_start_ms=133, response_duration_ms=1, request_bytes=612, response_bytes=331, cache_hit=0} 09:30:45.241674+0200 Runner Task .<1> finished successfully 09:30:45.242424+0200 Runner removing all entries config 0x280e80580 09:30:45.243762+0200 Runner Connection 13: is being canceled 09:30:45.243808+0200 Runner [C13 813E80DF-5F82-4040-8DB3-1A704A639534 app-measurement.com:443 tcp, url hash: 69ae1ed7, tls] cancel 09:30:45.244066+0200 Runner [C13 813E80DF-5F82-4040-8DB3-1A704A639534 app-measurement.com:443 tcp, url hash: 69ae1ed7, tls] cancelled [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 0.135s, DNS @0.001s took 0.003s, TCP @0.009s took 0.022s, TLS took 0.065s bytes in/out: 3920/1470, packets in/out: 8/10, rtt: 0.025s, retransmitted packets: 0, out-of-order packets: 0 09:30:45.244329+0200 Runner 0.000s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:30:45.244368+0200 Runner 0.000s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:30:45.244514+0200 Runner 0.001s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:30:45.244832+0200 Runner 0.004s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:30:45.245121+0200 Runner 0.004s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:30:45.245275+0200 Runner 0.005s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:30:45.245457+0200 Runner 0.006s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:30:45.245646+0200 Runner 0.006s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:30:45.245804+0200 Runner 0.009s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:30:45.245974+0200 Runner 0.031s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:30:45.246159+0200 Runner 0.031s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:30:45.246252+0200 Runner 0.096s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:30:45.246393+0200 Runner 0.096s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:30:45.246567+0200 Runner 0.097s [C13.1 64E35A3A-5276-491F-BAB6-8F7A090A299F 192.168.0.25:53764<->216.58.215.46:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:30:45.246655+0200 Runner 0.097s [C13 66A31DCE-B672-45A0-8384-032ABB314027 app-measurement.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:30:45.246872+0200 Runner 0.135s [C13] path:cancel 09:30:45.248303+0200 Runner boringssl_context_message_handler(2259) [C13.1:2][0x14bd63d60] Writing SSL3_RT_ALERT 2 bytes 09:30:45.248403+0200 Runner boringssl_context_handle_warning_alert(1894) [C13.1:2][0x14bd63d60] write alert, level: warning, description: close notify 09:30:45.248486+0200 Runner boringssl_session_disconnect(539) [C13.1:2][0x14bd63d60] SSL_shutdown 0 09:30:45.248850+0200 Runner nw_protocol_tcp_log_summary [C13.1:3] [B213160E-51F3-48DD-8565-717D2761E31D 192.168.0.25:53764<->216.58.215.46:443] Init: 1, Conn_Time: 20.950ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: process, rtt_upd: 5, rtt: 25.750ms, rtt_var: 6.562ms rtt_nc: 21.281ms, rtt_var_nc: 6.375ms 09:30:45.249216+0200 Runner nw_flow_disconnected [C13.1 216.58.215.46:443 cancelled channel-flow ((null))] Output protocol disconnected 09:30:45.249511+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C13] reporting state cancelled 09:30:45.250097+0200 Runner Connection 13: destroyed 09:30:45.250245+0200 Runner removing all entries config 0x280e80580 09:30:45.250658+0200 Runner tcp_output [C13.1:3] flags=[F.] seq=3086238208, ack=883573166, win=1024 state=FIN_WAIT_1 rcv_nxt=883573166, snd_una=3086238075 09:30:45.250883+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C13.1:2][0x14bd63d60] nw_protocol_boringssl_remove_input_handler forced true 09:30:45.250972+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C13.1:2][0x14bd63d60] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:30:45.272557+0200 Runner tcp_input [C13.1:3] flags=[F.] seq=883573166, ack=3086238209, win=248 state=FIN_WAIT_1 rcv_nxt=883573166, snd_una=3086238145 09:30:46.444334+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:46.444778+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:46.445796+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:46.448703+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17969427,+5.71994245>, radius:150.00m)"} 09:30:46.511929+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:46.513055+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:46.515791+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:46.516720+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:46.517532+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:46.518390+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:46.519452+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:46.557792+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.17969426720775,5.719942453379756, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:46 AM} 09:30:46.559981+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 6FFC7B7C-FE48-41F8-AA7B-1D0A5B1185AE 09:30:46.577541+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:46 AM} 09:30:46.578363+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:46 AM} 09:30:48.181740+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:30:48.181993+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:30:48.182185+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:30:48.183305+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17972999,+5.71985613>, radius:150.00m)"} 09:30:48.236978+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:30:48.237333+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:30:48.239485+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:30:48.239702+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:30:48.240320+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:30:48.241002+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:30:48.241504+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:30:48.247248+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 10F78782-8AAD-439F-817D-B632DBB487F3 09:30:48.251290+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.17972999094026,5.719856131123199, acy: 65.0, spd: -1.0, battery: 0.3400000035762787} {LogLevel.WARNING} {15 juin 2020 09:30:48 AM} 09:30:48.257994+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: []} {LogLevel.WARNING} {15 juin 2020 09:30:48 AM} 09:30:48.258327+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:30:48 AM} 09:30:54.355540+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:519B19 )} 09:30:54.355900+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:519B19 )} 09:30:54.356240+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:519B19 )} 09:30:54.356447+0200 Runner Running task: 09:30:54.357168+0200 Runner Starting task: 09:30:54.357461+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:30:54.357733+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:30:54.358161+0200 Runner submitTaskRequest: 09:30:54.358506+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:6796B3 09:30:54.358767+0200 Runner Establish daemon connection; interrupted: 0 09:30:54.443435+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:30:54.626900+0200 Runner Marking task (null) complete with success: 1 09:30:54.627934+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:519B19 09:30:54.628309+0200 Runner Establish daemon connection; interrupted: 0 09:30:55.978610+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:6796B3 )} 09:30:55.978676+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:6796B3 )} 09:30:55.978748+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:6796B3 )} 09:30:55.978806+0200 Runner Running task: 09:30:55.978869+0200 Runner Will expire BGTask activities: {( )} 09:30:55.978909+0200 Runner Will expire activities: {( )} 09:30:55.978979+0200 Runner Calling expiration handlers for activities: {( )} 09:30:55.979283+0200 Runner Starting task: 09:30:55.979825+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:30:55.979863+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:30:55.980173+0200 Runner submitTaskRequest: 09:30:55.980587+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:A07A74 09:30:55.980639+0200 Runner Establish daemon connection; interrupted: 0 09:30:56.048079+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:30:56.062802+0200 Runner Calling expiration handlers for tasks: {( )} 09:30:56.063433+0200 Runner Will expire BGTask activities: {( )} 09:30:56.063469+0200 Runner Will expire activities: {( )} 09:30:56.063859+0200 Runner Calling expiration handlers for activities: {( )} 09:30:56.064394+0200 Runner Calling expiration handlers for tasks: {( )} 09:30:56.064692+0200 Runner Will expire BGTask activities: {( )} 09:30:56.064732+0200 Runner Will expire activities: {( )} 09:30:56.064917+0200 Runner Calling expiration handlers for activities: {( )} 09:30:56.065387+0200 Runner Calling expiration handlers for tasks: {( )} 09:30:56.179999+0200 Runner Marking task (null) complete with success: 1 09:30:56.180048+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:6796B3 09:30:56.180099+0200 Runner Establish daemon connection; interrupted: 0 09:30:59.939486+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:A07A74 )} 09:30:59.939558+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:A07A74 )} 09:30:59.939687+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:A07A74 )} 09:30:59.939730+0200 Runner Running task: 09:30:59.945686+0200 Runner Starting task: 09:30:59.946443+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:30:59.947151+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:30:59.947782+0200 Runner submitTaskRequest: 09:30:59.948178+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:A0F4AC 09:30:59.948262+0200 Runner Establish daemon connection; interrupted: 0 09:31:00.052890+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:00.165802+0200 Runner Marking task (null) complete with success: 1 09:31:00.165889+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:A07A74 09:31:00.165976+0200 Runner Establish daemon connection; interrupted: 0 09:31:00.899054+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:A0F4AC )} 09:31:00.899091+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:A0F4AC )} 09:31:00.899125+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:A0F4AC )} 09:31:00.899148+0200 Runner Running task: 09:31:00.899194+0200 Runner Starting task: 09:31:00.899353+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:00.899429+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:00.899676+0200 Runner submitTaskRequest: 09:31:00.899715+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:D60C2C 09:31:00.899765+0200 Runner Establish daemon connection; interrupted: 0 09:31:00.908629+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:01.026729+0200 Runner Marking task (null) complete with success: 1 09:31:01.026926+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:A0F4AC 09:31:01.027075+0200 Runner Establish daemon connection; interrupted: 0 09:31:01.655678+0200 Runner success removing entry for host fcmtoken.googleapis.com config 0x280ef6be0 09:31:01.656506+0200 Runner Connection 4: is being canceled 09:31:01.656564+0200 Runner [C4 1A384237-F19A-46DC-8241-DC6E30B68B1F fcmtoken.googleapis.com:443 tcp, url hash: 89359b3a, tls] cancel 09:31:01.657227+0200 Runner [C4 1A384237-F19A-46DC-8241-DC6E30B68B1F fcmtoken.googleapis.com:443 tcp, url hash: 89359b3a, tls] cancelled [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 120.612s, DNS @0.000s took 0.000s, TCP @0.001s took 0.035s, TLS took 0.042s bytes in/out: 5602/2574, packets in/out: 17/17, rtt: 0.033s, retransmitted packets: 0, out-of-order packets: 0 09:31:01.657289+0200 Runner 0.000s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:31:01.657346+0200 Runner 0.000s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:31:01.657390+0200 Runner 0.000s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:31:01.657437+0200 Runner 0.000s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:31:01.657476+0200 Runner 0.000s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:31:01.657511+0200 Runner 0.001s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:31:01.657720+0200 Runner 0.001s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:31:01.657959+0200 Runner 0.001s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:31:01.658014+0200 Runner 0.001s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:31:01.658232+0200 Runner 0.036s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:31:01.658530+0200 Runner 0.036s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:31:01.658600+0200 Runner 0.078s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:31:01.658638+0200 Runner 0.078s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:31:01.658693+0200 Runner 0.078s [C4.1 83F04A4C-F989-46AB-A55E-B434FCF5439C 192.168.0.25:53748<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:31:01.658735+0200 Runner 0.078s [C4 63809946-0FAE-4E2E-A2C6-F5C1C0C1A759 fcmtoken.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:31:01.658901+0200 Runner 120.612s [C4] path:cancel 09:31:01.660187+0200 Runner boringssl_context_message_handler(2259) [C4.1:2][0x14bdd3430] Writing SSL3_RT_ALERT 2 bytes 09:31:01.660264+0200 Runner boringssl_context_handle_warning_alert(1894) [C4.1:2][0x14bdd3430] write alert, level: warning, description: close notify 09:31:01.660301+0200 Runner boringssl_session_disconnect(539) [C4.1:2][0x14bdd3430] SSL_shutdown 0 09:31:01.660433+0200 Runner nw_protocol_tcp_log_summary [C4.1:3] [27517B4C-0E20-40F4-A7A7-5F7D9D0A1267 192.168.0.25:53748<->216.58.204.106:443] Init: 1, Conn_Time: 34.894ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: kernel, rtt_upd: 13, rtt: 33.156ms, rtt_var: 5.750ms rtt_nc: 32.531ms, rtt_var_nc: 5.937ms 09:31:01.660521+0200 Runner nw_flow_disconnected [C4.1 216.58.204.106:443 cancelled channel-flow ((null))] Output protocol disconnected 09:31:01.660600+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C4] reporting state cancelled 09:31:01.660696+0200 Runner tcp_output [C4.1:3] flags=[F.] seq=3555830650, ack=44789691, win=1024 state=FIN_WAIT_1 rcv_nxt=44789691, snd_una=3555830587 09:31:01.660782+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C4.1:2][0x14bdd3430] nw_protocol_boringssl_remove_input_handler forced true 09:31:01.660825+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C4.1:2][0x14bdd3430] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:31:01.686425+0200 Runner tcp_input [C4.1:3] flags=[F.] seq=44789691, ack=3555830650, win=261 state=FIN_WAIT_1 rcv_nxt=44789691, snd_una=3555830650 09:31:01.686541+0200 Runner tcp_output [C4.1:3] flags=[F.] seq=3555830650, ack=44789692, win=1024 state=CLOSING rcv_nxt=44789692, snd_una=3555830650 09:31:02.774255+0200 Runner success removing entry for host firebaseremoteconfig.googleapis.com config 0x280eff940 09:31:02.774310+0200 Runner Connection 5: is being canceled 09:31:02.774394+0200 Runner [C5 2FB32316-57BC-4548-835F-1C23AF1754DF firebaseremoteconfig.googleapis.com:443 tcp, url hash: 42438b3c, tls] cancel 09:31:02.774607+0200 Runner [C5 2FB32316-57BC-4548-835F-1C23AF1754DF firebaseremoteconfig.googleapis.com:443 tcp, url hash: 42438b3c, tls] cancelled [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 120.722s, DNS @0.000s took 0.001s, TCP @0.001s took 0.030s, TLS took 0.044s bytes in/out: 4634/1448, packets in/out: 9/10, rtt: 0.028s, retransmitted packets: 0, out-of-order packets: 0 09:31:02.774681+0200 Runner 0.000s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:31:02.774821+0200 Runner 0.000s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:31:02.774897+0200 Runner 0.000s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:31:02.775012+0200 Runner 0.001s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:31:02.775085+0200 Runner 0.001s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:31:02.775196+0200 Runner 0.001s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:31:02.775343+0200 Runner 0.001s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:31:02.775470+0200 Runner 0.001s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:31:02.775517+0200 Runner 0.001s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:31:02.775891+0200 Runner 0.021s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:31:02.776272+0200 Runner 0.031s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:31:02.776329+0200 Runner 0.031s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:31:02.776451+0200 Runner 0.075s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:31:02.776534+0200 Runner 0.075s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:31:02.776609+0200 Runner 0.075s [C5.1 B24643FA-58A5-4CA4-B274-DBC1AB8933AE 192.168.0.25:53749<->172.217.22.138:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:31:02.776720+0200 Runner 0.075s [C5 E160E9B4-81C5-4692-9FDA-C46EA57D58F2 firebaseremoteconfig.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:31:02.776793+0200 Runner 120.721s [C5] path:cancel 09:31:02.778024+0200 Runner boringssl_context_message_handler(2259) [C5.1:2][0x14bec8e80] Writing SSL3_RT_ALERT 2 bytes 09:31:02.778089+0200 Runner boringssl_context_handle_warning_alert(1894) [C5.1:2][0x14bec8e80] write alert, level: warning, description: close notify 09:31:02.778183+0200 Runner boringssl_session_disconnect(539) [C5.1:2][0x14bec8e80] SSL_shutdown 0 09:31:02.778582+0200 Runner nw_protocol_tcp_log_summary [C5.1:3] [0F9F6B4B-A772-41F2-8355-12BDF796EB40 192.168.0.25:53749<->172.217.22.138:443] Init: 1, Conn_Time: 29.202ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: kernel, rtt_upd: 7, rtt: 28.750ms, rtt_var: 11.000ms rtt_nc: 32.312ms, rtt_var_nc: 8.500ms 09:31:02.778759+0200 Runner nw_flow_disconnected [C5.1 172.217.22.138:443 cancelled channel-flow ((null))] Output protocol disconnected 09:31:02.779211+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C5] reporting state cancelled 09:31:02.779414+0200 Runner tcp_output [C5.1:3] flags=[F.] seq=773314020, ack=3289975465, win=1024 state=FIN_WAIT_1 rcv_nxt=3289975465, snd_una=773313957 09:31:02.779592+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C5.1:2][0x14bec8e80] nw_protocol_boringssl_remove_input_handler forced true 09:31:02.779625+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C5.1:2][0x14bec8e80] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:31:02.815377+0200 Runner tcp_input [C5.1:3] flags=[F.] seq=3289975465, ack=773314020, win=248 state=FIN_WAIT_1 rcv_nxt=3289975465, snd_una=773314020 09:31:02.815486+0200 Runner tcp_output [C5.1:3] flags=[F.] seq=773314020, ack=3289975466, win=1024 state=CLOSING rcv_nxt=3289975466, snd_una=773314020 09:31:06.813659+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:D60C2C )} 09:31:06.813725+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:D60C2C )} 09:31:06.813775+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:D60C2C )} 09:31:06.813812+0200 Runner Running task: 09:31:06.814105+0200 Runner Starting task: 09:31:06.814449+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:06.814928+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:06.815222+0200 Runner submitTaskRequest: 09:31:06.815397+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:9BC29E 09:31:06.815435+0200 Runner Establish daemon connection; interrupted: 0 09:31:06.836294+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:07.035794+0200 Runner Marking task (null) complete with success: 1 09:31:07.035994+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:D60C2C 09:31:07.036133+0200 Runner Establish daemon connection; interrupted: 0 09:31:09.203510+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:9BC29E )} 09:31:09.203567+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:9BC29E )} 09:31:09.203696+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:9BC29E )} 09:31:09.203727+0200 Runner Running task: 09:31:09.203877+0200 Runner Starting task: 09:31:09.203985+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:09.204113+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:09.204299+0200 Runner submitTaskRequest: 09:31:09.204464+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:743F14 09:31:09.204566+0200 Runner Establish daemon connection; interrupted: 0 09:31:09.223997+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:09.407727+0200 Runner Marking task (null) complete with success: 1 09:31:09.407980+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:9BC29E 09:31:09.408127+0200 Runner Establish daemon connection; interrupted: 0 09:31:18.176461+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:743F14 )} 09:31:18.176507+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:743F14 )} 09:31:18.176595+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:743F14 )} 09:31:18.176630+0200 Runner Running task: 09:31:18.176933+0200 Runner Starting task: 09:31:18.178298+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:18.178776+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:18.179178+0200 Runner submitTaskRequest: 09:31:18.179619+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:DAA282 09:31:18.179750+0200 Runner Establish daemon connection; interrupted: 0 09:31:18.279310+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:18.380212+0200 Runner Marking task (null) complete with success: 1 09:31:18.380306+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:743F14 09:31:18.380399+0200 Runner Establish daemon connection; interrupted: 0 09:31:31.591001+0200 Runner success removing entry for host firebaselogging-pa.googleapis.com config 0x280eb5100 09:31:31.598685+0200 Runner Connection 8: is being canceled 09:31:31.599996+0200 Runner [C8 EC4E1043-4005-4FA3-B2D6-81020D269F47 firebaselogging-pa.googleapis.com:443 tcp, url hash: e23dcc20, tls] cancel 09:31:31.649377+0200 Runner [C8 EC4E1043-4005-4FA3-B2D6-81020D269F47 firebaselogging-pa.googleapis.com:443 tcp, url hash: e23dcc20, tls] cancelled [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443] Connected Path: satisfied (Path is satisfied), interface: en0, ipv4, dns Duration: 120.827s, DNS @0.001s took 0.008s, TCP @0.014s took 0.032s, TLS took 0.057s bytes in/out: 4223/1492, packets in/out: 9/10, rtt: 0.027s, retransmitted packets: 0, out-of-order packets: 0 09:31:31.649684+0200 Runner 0.000s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:31:31.649730+0200 Runner 0.001s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:31:31.650016+0200 Runner 0.001s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:start_dns 09:31:31.650089+0200 Runner 0.009s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:31:31.650130+0200 Runner 0.009s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:start 09:31:31.650345+0200 Runner 0.010s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] path:satisfied 09:31:31.650410+0200 Runner 0.011s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_nexus 09:31:31.650474+0200 Runner 0.011s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:receive_nexus 09:31:31.650531+0200 Runner 0.014s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:start_connect 09:31:31.650571+0200 Runner 0.030s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] resolver:receive_dns 09:31:31.650732+0200 Runner 0.046s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:31:31.650784+0200 Runner 0.046s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_transport 09:31:31.650887+0200 Runner 0.103s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:31:31.650973+0200 Runner 0.103s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:finish_connect 09:31:31.651108+0200 Runner 0.103s [C8.1 50BFC8A3-DC77-4389-8FCC-896B83C80D50 192.168.0.25:53752<->216.58.204.106:443 channel-flow path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:31:31.651388+0200 Runner 0.103s [C8 F5FA14B8-10CA-4ECE-8324-601C4C0405D8 firebaselogging-pa.googleapis.com:443 resolver path=satisfied (Path is satisfied), interface: en0, ipv4, dns] flow:changed_viability 09:31:31.651456+0200 Runner 120.778s [C8] path:cancel 09:31:31.652576+0200 Runner boringssl_context_message_handler(2259) [C8.1:2][0x14db06270] Writing SSL3_RT_ALERT 2 bytes 09:31:31.652653+0200 Runner boringssl_context_handle_warning_alert(1894) [C8.1:2][0x14db06270] write alert, level: warning, description: close notify 09:31:31.652721+0200 Runner boringssl_session_disconnect(539) [C8.1:2][0x14db06270] SSL_shutdown 0 09:31:31.652862+0200 Runner nw_protocol_tcp_log_summary [C8.1:3] [010A4096-B8CB-4A9E-8319-C5CC78820D3F 192.168.0.25:53752<->216.58.204.106:443] Init: 1, Conn_Time: 31.369ms, Syn's: 1, WR_T: 0/0, RD_T: 0/0, TFO: 0/0/0, ECN: 0/0/0, TS: 1 RTT_Cache: process, rtt_upd: 7, rtt: 27.593ms, rtt_var: 9.000ms rtt_nc: 25.125ms, rtt_var_nc: 8.687ms 09:31:31.652945+0200 Runner nw_flow_disconnected [C8.1 216.58.204.106:443 cancelled channel-flow ((null))] Output protocol disconnected 09:31:31.653245+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C8] reporting state cancelled 09:31:31.653542+0200 Runner tcp_output [C8.1:3] flags=[F.] seq=3168800972, ack=4019212902, win=1024 state=FIN_WAIT_1 rcv_nxt=4019212902, snd_una=3168800909 09:31:31.653594+0200 Runner nw_protocol_boringssl_remove_input_handler(1010) [C8.1:2][0x14db06270] nw_protocol_boringssl_remove_input_handler forced true 09:31:31.653654+0200 Runner nw_protocol_boringssl_remove_input_handler(1028) [C8.1:2][0x14db06270] Transferring nw_protocol_boringssl_t handle back into ARC for autorelease 09:31:31.679603+0200 Runner tcp_input [C8.1:3] flags=[F.] seq=4019212902, ack=3168800972, win=248 state=FIN_WAIT_1 rcv_nxt=4019212902, snd_una=3168800972 09:31:31.681380+0200 Runner tcp_output [C8.1:3] flags=[F.] seq=3168800972, ack=4019212903, win=1024 state=CLOSING rcv_nxt=4019212903, snd_una=3168800972 09:31:34.035412+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:DAA282 )} 09:31:34.035492+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:DAA282 )} 09:31:34.035564+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:DAA282 )} 09:31:34.035867+0200 Runner Running task: 09:31:34.036386+0200 Runner Starting task: 09:31:34.036694+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:34.036953+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:34.037375+0200 Runner submitTaskRequest: 09:31:34.037625+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:A8B50D 09:31:34.037974+0200 Runner Establish daemon connection; interrupted: 0 09:31:34.053128+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:34.181612+0200 Runner Marking task (null) complete with success: 1 09:31:34.181849+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:DAA282 09:31:34.181926+0200 Runner Establish daemon connection; interrupted: 0 09:31:43.861641+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:A8B50D )} 09:31:43.861705+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:A8B50D )} 09:31:43.861795+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:A8B50D )} 09:31:43.861838+0200 Runner Running task: 09:31:43.862158+0200 Runner Starting task: 09:31:43.862459+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:43.862777+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:43.863018+0200 Runner submitTaskRequest: 09:31:43.863333+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:8324AA 09:31:43.863595+0200 Runner Establish daemon connection; interrupted: 0 09:31:43.887835+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:43.993786+0200 Runner Handling launch from daemon for activities: {( bgRefresh-com.transistorsoft.fetch:8324AA )} 09:31:43.993854+0200 Runner Application launched for activities: {( bgRefresh-com.transistorsoft.fetch:8324AA )} 09:31:43.993923+0200 Runner Calling handlers for activities: {( bgRefresh-com.transistorsoft.fetch:8324AA )} 09:31:43.994092+0200 Runner Running task: 09:31:43.994549+0200 Runner Starting task: 09:31:43.994749+0200 Runner [TSBackgroundFetch handleBGAppRefreshTask] 09:31:43.995001+0200 Runner [TSBackgroundFetch scheduleBGAppRefresh] com.transistorsoft.fetch 09:31:43.995340+0200 Runner submitTaskRequest: 09:31:43.995631+0200 Runner Submitting task request activity: bgRefresh-com.transistorsoft.fetch:2675C2 09:31:43.995878+0200 Runner Establish daemon connection; interrupted: 0 09:31:44.046744+0200 Runner [TSBackgroundFetch finish] TSLocationManager (1 of 1) 09:31:44.051203+0200 Runner Marking task (null) complete with success: 1 09:31:44.051335+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:A8B50D 09:31:44.051403+0200 Runner Establish daemon connection; interrupted: 0 09:31:44.204619+0200 Runner Marking task (null) complete with success: 1 09:31:44.204890+0200 Runner Complete task request activity: bgRefresh-com.transistorsoft.fetch:8324AA 09:31:44.204931+0200 Runner Establish daemon connection; interrupted: 0 09:31:54.302478+0200 Runner Will expire BGTask activities: {( )} 09:31:54.303154+0200 Runner Will expire activities: {( )} 09:31:54.303602+0200 Runner Calling expiration handlers for activities: {( )} 09:31:54.303931+0200 Runner Calling expiration handlers for tasks: {( )} 09:31:57.827860+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:31:57.860313+0200 Runner 🔵-[BackgroundTaskManager onResume:] Current preventSuspend tasks: 1 09:31:57.860359+0200 Runner 🔵-[TSLocationManager onResume:] enabled? 1 09:31:57.905287+0200 Runner ℹ️-[LocationDAO purge:] 1 09:31:57.950875+0200 Runner Task .<2> resuming, QOS(0x15) Voucher (null) 09:31:57.950947+0200 Runner Task .<2> {strength 1, tls 8, ct 0, sub 0, sig 0, ciphers 1, bundle 0, builtin 0} 09:31:57.951077+0200 Runner Connection 14: enabling TLS 09:31:57.951155+0200 Runner Connection 14: starting, TC(0x0) 09:31:57.951217+0200 Runner [C14 6D5FE00C-5B03-4263-9C10-00E0FA986404 firebaselogging-pa.googleapis.com:443 tcp, url hash: e23dcc20, tls] start 09:31:57.954657+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C14] reporting state preparing 09:31:57.961822+0200 Runner Task .<2> setting up Connection 14 09:31:57.970220+0200 Runner tcp_output [C14.1:3] flags=[S] seq=1618373669, ack=0, win=65535 state=SYN_SENT rcv_nxt=0, snd_una=1618373669 09:31:58.028382+0200 Runner tcp_input [C14.1:3] flags=[S.] seq=762075456, ack=1618373670, win=60192 state=SYN_SENT rcv_nxt=0, snd_una=1618373669 09:31:58.028445+0200 Runner nw_flow_connected [C14.1 216.58.201.234:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Transport protocol connected 09:31:58.029007+0200 Runner boringssl_context_set_handshake_config(1472) [0x14db04e60] set tls_handshake_config_standard 09:31:58.029066+0200 Runner boringssl_context_set_min_version(326) [0x14db04e60] set 0x0301 09:31:58.029143+0200 Runner boringssl_context_set_max_version(310) [0x14db04e60] set 0x0304 09:31:58.029304+0200 Runner boringssl_context_set_cipher_suites(844) [0x14db04e60] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305:AES256-GCM-SHA384:AES128-GCM-SHA256:AES256-SHA256:AES128-SHA256:AES256-SHA:AES128-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-AES256-SHA:AES256-SHA:AES128-SHA:ECDHE-ECDSA-DES-CBC3-SHA:ECDHE-RSA-DES-CBC3-SHA:DES-CBC3-SHA 09:31:58.029397+0200 Runner boringssl_context_set_remote_address(2556) [0x14db04e60] Saving remote IPv4 address 09:31:58.029509+0200 Runner boringssl_session_install_association_state(1285) [0x14db04e60] Client session cache hit 09:31:58.030241+0200 Runner boringssl_session_set_session_state(1253) [0x14db04e60] Not resuming session to a different address if EMS was not negotiated 09:31:58.030350+0200 Runner boringssl_session_set_peer_hostname(1181) [0x14db04e60] SNI firebaselogging-pa.googleapis.com 09:31:58.030411+0200 Runner boringssl_context_set_min_version(326) [C14.1:2][0x14db04e60] set 0x0303 09:31:58.030478+0200 Runner boringssl_context_set_session_ticket_enabled(441) [C14.1:2][0x14db04e60] set false 09:31:58.030543+0200 Runner boringssl_context_set_false_start(411) [C14.1:2][0x14db04e60] set false 09:31:58.030608+0200 Runner boringssl_context_set_enforce_ev(401) [C14.1:2][0x14db04e60] set false 09:31:58.030778+0200 Runner boringssl_context_set_ats_enforced(1312) [C14.1:2][0x14db04e60] set false 09:31:58.030849+0200 Runner boringssl_context_set_ats_minimum_rsa_key_size(1321) [C14.1:2][0x14db04e60] set 0 09:31:58.030937+0200 Runner boringssl_context_set_ats_minimum_ecdsa_key_size(1330) [C14.1:2][0x14db04e60] set 0 09:31:58.031752+0200 Runner boringssl_context_set_ats_minimum_signature_algorithm(1340) [C14.1:2][0x14db04e60] set 0 09:31:58.031826+0200 Runner boringssl_session_set_peer_hostname(1181) [C14.1:2][0x14db04e60] SNI firebaselogging-pa.googleapis.com 09:31:58.032221+0200 Runner boringssl_context_set_cipher_suites(844) [C14.1:2][0x14db04e60] Ciphersuite string: AEAD-AES128-GCM-SHA256:AEAD-AES256-GCM-SHA384:AEAD-CHACHA20-POLY1305-SHA256:ECDHE-ECDSA-AES256-GCM-SHA384:ECDHE-ECDSA-AES128-GCM-SHA256:ECDHE-ECDSA-AES256-SHA384:ECDHE-ECDSA-AES128-SHA256:ECDHE-ECDSA-AES256-SHA:ECDHE-ECDSA-AES128-SHA:ECDHE-ECDSA-CHACHA20-POLY1305:ECDHE-RSA-AES256-GCM-SHA384:ECDHE-RSA-AES128-GCM-SHA256:ECDHE-RSA-AES256-SHA384:ECDHE-RSA-AES128-SHA256:ECDHE-RSA-AES256-SHA:ECDHE-RSA-AES128-SHA:ECDHE-RSA-CHACHA20-POLY1305 09:31:58.032299+0200 Runner nw_protocol_boringssl_begin_connection(495) [C14.1:2][0x14db04e60] early data disabled 09:31:58.032408+0200 Runner boringssl_context_info_handler(1971) [C14.1:2][0x14db04e60] Client handshake started 09:31:58.032490+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Writing SSL3_RT_HANDSHAKE 512 bytes 09:31:58.032562+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS client enter_early_data 09:31:58.032630+0200 Runner boringssl_context_add_handshake_message_pending(579) [C14.1:2][0x14db04e60] Adding message(1) 09:31:58.032722+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS client read_server_hello 09:31:58.039743+0200 Runner boringssl_context_add_handshake_message_pending(579) [C14.1:2][0x14db04e60] Adding message(2) 09:31:58.039834+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:31:58.039905+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:31:58.045254+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:31:58.045327+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:31:58.151846+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:31:58.151967+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_READ [2] 09:31:58.166807+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 122 bytes 09:31:58.166881+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_hello_retry_request 09:31:58.166949+0200 Runner boringssl_context_add_handshake_message_pending(579) [C14.1:2][0x14db04e60] Adding message(2) 09:31:58.167025+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Writing SSL3_RT_CHANGE_CIPHER_SPEC 1 bytes 09:31:58.167093+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_server_hello 09:31:58.167337+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_encrypted_extensions 09:31:58.167406+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 15 bytes 09:31:58.167474+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_certificate_request 09:31:58.167541+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 2634 bytes 09:31:58.167608+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_server_certificate 09:31:58.167690+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_server_certificate_verify 09:31:58.167757+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 80 bytes 09:31:58.167824+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C14.1:2][0x14db04e60] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:31:58.171237+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C14.1:2][0x14db04e60] SecTrustCreateWithCertificates result: 0 09:31:58.171652+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C14.1:2][0x14db04e60] No TLS-provided OCSP response 09:31:58.171722+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C14.1:2][0x14db04e60] No TLS-provided SCTs 09:31:58.179502+0200 Runner boringssl_context_certificate_verify_callback(2072) [C14.1:2][0x14db04e60] Asyncing for verify block 09:31:58.180125+0200 Runner boringssl_session_handshake_incomplete(191) [C14.1:2][0x14db04e60] Early handshake return caused by SSL_ERROR_WANT_CERTIFICATE_VERIFY [16] 09:31:58.180207+0200 Runner Connection 14: asked to evaluate TLS Trust 09:31:58.180332+0200 Runner Task .<2> auth completion disp=1 cred=0x0 09:31:58.181830+0200 Runner System Trust Evaluation yielded status(0) 09:31:58.183814+0200 Runner Connection 14: TLS Trust result 0 09:31:58.183969+0200 Runner boringssl_context_certificate_verify_callback_block_invoke_3(2081) [C14.1:2][0x14db04e60] Returning from verify block 09:31:58.184175+0200 Runner boringssl_context_certificate_verify_callback(2048) [C14.1:2][0x14db04e60] Setting trust result to ssl_verify_ok 09:31:58.184292+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client read_server_finished 09:31:58.184442+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 36 bytes 09:31:58.184499+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client send_end_of_early_data 09:31:58.184772+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client send_client_certificate 09:31:58.184850+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client complete_second_flight 09:31:58.184890+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Writing SSL3_RT_HANDSHAKE 36 bytes 09:31:58.184947+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS 1.3 client done 09:31:58.184990+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS client finish_client_handshake 09:31:58.185045+0200 Runner boringssl_context_info_handler(1984) [C14.1:2][0x14db04e60] Client handshake state: TLS client done 09:31:58.185096+0200 Runner boringssl_context_copy_peer_sct_list(1004) [C14.1:2][0x14db04e60] SSL_get0_signed_cert_timestamp_list returned no SCT extension data 09:31:58.185189+0200 Runner boringssl_helper_create_sec_trust_with_certificates(607) [C14.1:2][0x14db04e60] SecTrustCreateWithCertificates result: 0 09:31:58.185223+0200 Runner boringssl_helper_create_sec_trust_with_certificates(614) [C14.1:2][0x14db04e60] No TLS-provided OCSP response 09:31:58.185292+0200 Runner boringssl_helper_create_sec_trust_with_certificates(621) [C14.1:2][0x14db04e60] No TLS-provided SCTs 09:31:58.185381+0200 Runner boringssl_context_add_handshake_message_pending(579) [C14.1:2][0x14db04e60] Adding message(20) 09:31:58.185436+0200 Runner boringssl_context_info_handler(1975) [C14.1:2][0x14db04e60] Client handshake done 09:31:58.185480+0200 Runner nw_protocol_boringssl_signal_connected(699) [C14.1:2][0x14db04e60] TLS connected [version(0x0304) ciphersuite(0x1301) group(0x001d) peer_key(0x0403) alpn(h2) resumed(0) offered_ticket(0) false_started(0) ocsp(0) sct(0)] 09:31:58.185658+0200 Runner nw_flow_connected [C14.1 216.58.201.234:443 in_progress channel-flow (satisfied (Path is satisfied), interface: en0, ipv4, dns)] Output protocol connected 09:31:58.186502+0200 Runner nw_connection_report_state_with_handler_on_nw_queue [C14] reporting state ready 09:31:58.186779+0200 Runner Connection 14: connected successfully 09:31:58.186906+0200 Runner Connection 14: TLS handshake complete 09:31:58.186956+0200 Runner Connection 14: ready C(N) E(N) 09:31:58.187027+0200 Runner new connection to firebaselogging-pa.googleapis.com config 0x280eb5100 09:31:58.187052+0200 Runner Task .<2> now using Connection 14 09:31:58.187114+0200 Runner Connection 14: received viability advisory(Y) 09:31:58.187443+0200 Runner Task .<2> sent request, body S 405 09:31:58.203723+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 248 bytes 09:31:58.203763+0200 Runner boringssl_context_new_session_handler(1118) [C14.1:2][0x14db04e60] New session available 09:31:58.203794+0200 Runner boringssl_context_message_handler(2259) [C14.1:2][0x14db04e60] Reading SSL3_RT_HANDSHAKE 248 bytes 09:31:58.203866+0200 Runner boringssl_context_new_session_handler(1118) [C14.1:2][0x14db04e60] New session available 09:31:58.203907+0200 Runner Task .<2> received response, status 200 content K 09:31:58.203957+0200 Runner Task .<2> done using Connection 14 09:31:58.204009+0200 Runner Task .<2> response ended 09:31:58.205452+0200 Runner Task .<2> summary for task success {transaction_duration_ms=133, response_status=200, connection=14, protocol="h2", domain_lookup_duration_ms=3, connect_duration_ms=81, secure_connection_duration_ms=59, request_start_ms=95, request_duration_ms=0, response_start_ms=124, response_duration_ms=8, request_bytes=595, response_bytes=462, cache_hit=1} 09:31:58.353017+0200 Runner HTHangEventCreate: HangTracing is disabled. Not creating a new event. 09:31:58.353668+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280caad90", "notification":"NSConcreteNotification 0x280022400 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:31:58.353785+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280caadc0", "notification":"NSConcreteNotification 0x280022400 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:31:58.353909+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280caae60", "notification":"NSConcreteNotification 0x280022400 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:31:58.354036+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280ca68b0", "notification":"NSConcreteNotification 0x280022400 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:31:58.354161+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280ca6a70", "notification":"NSConcreteNotification 0x280022400 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:31:58.354276+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"onDidBecomeActive:", "self":"0x280ca6a60", "notification":"NSConcreteNotification 0x280022400 {name = UIApplicationDidBecomeActiveNotification; object = }"} 09:31:58.382450+0200 Runner Read options: 0 -- URL: file:///var/mobile/Containers/Data/Application/3D934029-4918-44EA-A0C2-588F4808DF33/Library/Application%20Support/Google/FIRApp/FIREBASE_DIAGNOSTICS_HEARTBEAT_DATE -- purposeID: A8A14FF0-DD9B-4FA0-B687-F0E86244A742 -- claimID: 2619DD81-7451-4E35-9CE1-DF86E50A7C26 09:31:58.412237+0200 Runner Claim 2619DD81-7451-4E35-9CE1-DF86E50A7C26 granted in client 09:31:58.412295+0200 Runner Claim 2619DD81-7451-4E35-9CE1-DF86E50A7C26 invoked in client 09:32:01.193151+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caad90", "delegate":"0x14bd03460"} 09:32:01.193339+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"distanceFilter", "self":"0x280caad90"} 09:32:01.193537+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:32:01.196647+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17982841,+5.71987501>, radius:150.00m)"} 09:32:01.199039+0200 Runner 🔵-[TSLocationManager calculateMedianLocationAccuracy:] Median location accuracy: 65.0 09:32:01.250045+0200 Runner ✅-[TSLocationManager persistLocation:]_block_invoke INSERT: 9E3E397A-0664-4E21-AFA7-B1CD613B262E 09:32:01.268769+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caad90"} 09:32:01.269112+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280caadc0"} 09:32:01.269354+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a60"} 09:32:01.269545+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca68b0"} 09:32:01.271542+0200 Runner {"msg":"Delivering region monitoring state request, no delegate", "self":"0x280ca6a70"} 09:32:01.271795+0200 Runner {"msg":"Delivering region monitoring state request to client's delegate", "self":"0x280caae60", "delegate":"0x283c838d0"} 09:32:01.278969+0200 Runner flutter: {TrackingService} {_updateLocation} {location updated: coords: 45.17982841007894,5.719875013461075, acy: 30.0, spd: 0.0, battery: 0.3499999940395355} {LogLevel.WARNING} {15 juin 2020 09:32:01 AM} 09:32:01.289897+0200 Runner flutter: {LocationService} {_log} {POIs détectés autour de l'utilisateur: [NavigationPOI(distance: 24.0, poi: POI(id: 128008, order: 11, title: Tournez légèrement à gauche, lat: 45.17963, lng: 5.71977, distance: 379, direction: -1))]} {LogLevel.WARNING} {15 juin 2020 09:32:01 AM} 09:32:01.289985+0200 Runner flutter: {LocationService} {_log} {POI POI(id: 128008, order: 11, title: Tournez légèrement à gauche, lat: 45.17963, lng: 5.71977, distance: 379, direction: -1) ne respecte pas l'ordre null mais est stocké et le prochain sera peut-être déclenché} {LogLevel.WARNING} {15 juin 2020 09:32:01 AM} 09:32:01.290060+0200 Runner flutter: {LocationService} {_log} {Pas de POI retourné} {LogLevel.WARNING} {15 juin 2020 09:32:01 AM} 09:32:11.762842+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopMonitoringSignificantLocationChanges", "self":"0x280caae60"} 09:32:11.763071+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopUpdatingLocation", "self":"0x280caae60"} 09:32:11.763154+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caae60", "property":"updatingLocation", "old":0, "new":0} 09:32:11.763422+0200 Runner ℹ️-[TSLocationManager clearLastOdometerLocation] 09:32:11.763659+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"monitoredRegions", "self":"0x280caae60"} 09:32:11.765074+0200 Runner 🔴-[TSGeofenceManager stop] 09:32:11.765300+0200 Runner 🔴-[TSGeofenceManager stopMonitoringGeofences] 09:32:11.765538+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopUpdatingLocation", "self":"0x280caad90"} 09:32:11.765647+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caad90", "property":"updatingLocation", "old":1, "new":0} 09:32:11.766985+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"monitoredRegions", "self":"0x280caad90"} 09:32:11.767342+0200 Runner 🔴-[SOMotionDetector stopDetection] OFF 09:32:11.767493+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopMonitoringForRegion:", "self":"0x280caad90", "region":"CLCircularRegion (identifier:'__PROVISIONAL_ALWAYS_REGION__', center:<+45.17982841,+5.71987501>, radius:150.00m)"} 09:32:11.767600+0200 Runner #Warning Sending an un-cached message 'kCLConnectionMessageMotionActivityUpdate' without first clearing the previously cached value 09:32:11.768829+0200 Runner 🔴-[TSLocationManager stopUpdatingLocation] 09:32:11.768954+0200 Runner 🔴-[TSLocationManager stopMonitoringBackgroundFetch] BackgroundFetch: OFF 09:32:11.769046+0200 Runner cancelTaskRequestWithIdentifier: com.transistorsoft.fetch 09:32:11.769114+0200 Runner Establish daemon connection; interrupted: 0 09:32:11.769301+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopMonitoringSignificantLocationChanges", "self":"0x280caad90"} 09:32:11.769349+0200 Runner 🔴-[TSLocationManager stopMonitoringSignificantLocationChanges] 09:32:11.770524+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"startUpdatingLocation", "self":"0x280caadc0"} 09:32:11.770776+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caadc0", "property":"updatingLocation", "old":0, "new":1} 09:32:11.770816+0200 Runner 🔵-[TSLocationManager setOdometer:request:] 09:32:11.771047+0200 Runner {"msg":"CLInternalGetAuthorizationStatus", "event":"activity"} 09:32:11.772850+0200 Runner 🔵-[TSLocationManager getCurrentPosition:] 09:32:11.772887+0200 Runner 🎾-[LocationManager startUpdatingLocation] ON 09:32:11.798283+0200 Runner ℹ️+[LocationAuthorization run:onCancel:] status: 3 09:32:11.819864+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caadc0", "delegate":"0x283c83690"} 09:32:12.228569+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caadc0", "delegate":"0x283c83690"} 09:32:13.111696+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caadc0", "delegate":"0x283c83690"} 09:32:14.135874+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caadc0", "delegate":"0x283c83690"} 09:32:15.146291+0200 Runner {"msg":"delivering locations to client's delegate", "self":"0x280caadc0", "delegate":"0x283c83690"} 09:32:15.146409+0200 Runner {"msg":"CLLocationManager", "event":"activity", "_cmd":"stopUpdatingLocation", "self":"0x280caadc0"} 09:32:15.146470+0200 Runner {"msg":"state transition", "event":"state_transition", "state":"LocationManager", "id":"0x280caadc0", "property":"updatingLocation", "old":1, "new":0} 09:32:15.155581+0200 Runner 🔴-[LocationManager stopUpdatingLocation] OFF ```

[christocracy]

Sounds like you're experiencing the new changes to iOS 13 Location Authorization. Have you read my blog entry about it from last year?

[christocracy]

You can easily test the "first app launch" scenario in the iOS Simulator, simulating location with Freeway Drive.

[HugoHeneault]

@christocracy Yes I already read your post (see discussion above).

But it's not working as you said:

WhenInUse will continue tracking in background ONLY IF you engage location-services while the app is in the foreground with BackgroundGeolocation.changePace(true).

How is Strava handling it? We don't need to lock/unlock the device on first app launch to get it working in background?

Thanks for your support. Appreciate it 👍