Long running test in sleep mode

[hannesa2]

close #604 #609

[hannesa2]

Now I can reproduce the #609 "Unexplained websocket disconnection" error in CI

[hannesa2]

I can't reproduce the connection lost issue

[hannesa2]

I can't reproduce the connection lost issue

I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 34 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 33 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 32 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 31 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 30 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 29 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 28 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 27 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 26 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 25 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 24 seconds
D (PingWorker.kt:19): .doWork(): Sending Ping at: 2024-10-31 07:51:56Z
D (AlarmPingSender.kt:46): .schedule(): Schedule next alarm at 2024-10-31 07:55:16Z
D (PingWorker.kt:24): $doWork.onSuccess(): Success null
D (PingWorker.kt:24): $doWork.onSuccess(): Success null
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 23 seconds
D CarrierSvcBindHelper: onPackageModified: info.mqtt.android.extsample
D CarrierSvcBindHelper: No carrier app for: 0
D ImsResolver: maybeAddedImsService, packageName: info.mqtt.android.extsample
D ControlsListingControllerImpl: ServiceConfig reloaded, count: 0
I PermissionControllerServiceImpl: Updating user sensitive for uid 10195
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 22 seconds
D ControlsListingControllerImpl: Subscribing callback, service count: 0
D ControlsListingControllerImpl: Unsubscribing callback
I NamespaceManager: Package com.android.refreshratecontrol name not found! Using module version.
I NamespaceManager: Package com.google.oslo name not found! Using module version.
I NamespaceManager: Package com.android.media name not found! Using module version.
I NamespaceManager: Package com.android.media.swcodec name not found! Using module version.
I NamespaceManager: Package com.android.appsearch name not found! Using module version.
I NamespaceManager: Package com.android.neuralnetworks name not found! Using module version.
I NamespaceManager: Package com.android.media name not found! Using module version.
I NamespaceManager: Package 1 name not found! Using module version.
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 21 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 20 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 19 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 18 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 17 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 16 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 15 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 14 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 13 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 12 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 11 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 10 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 9 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 8 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 7 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 6 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 5 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 4 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 3 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 2 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 1 seconds
I (ExtendedPublishSleepTest.kt:87): .connectWaitAndPublish(): wait for 0 seconds
I SideFpsEventHandler: notifyPowerPressed
D LifecycleMonitor: Lifecycle status change: info.mqtt.android.extsample.MainActivity@d8ca1e6 in: RESTARTED
V ActivityScenario: Update currentActivityStage to RESTARTED, currentActivity=info.mqtt.android.extsample.MainActivity@d8ca1e6
D CentralSurfaces: mShouldDelayWakeUpAnimation CLEARED
D LifecycleMonitor: Lifecycle status change: info.mqtt.android.extsample.MainActivity@d8ca1e6 in: STARTED
V ActivityScenario: Update currentActivityStage to STARTED, currentActivity=info.mqtt.android.extsample.MainActivity@d8ca1e6
D LifecycleMonitor: Lifecycle status change: info.mqtt.android.extsample.MainActivity@d8ca1e6 in: RESUMED
V ActivityScenario: Update currentActivityStage to RESUMED, currentActivity=info.mqtt.android.extsample.MainActivity@d8ca1e6
D FastPairService: onReceive: ACTION_SCREEN_ON
D WifiNl80211Manager: Scan result ready event
D WifiNative: Scan result ready event
I (ExtendedPublishSleepTest.kt:92): .connectWaitAndPublish(): Wakeup device
D (PublishFragment.kt:35): .onCreate(): CONNECTION_KEY=PWI2XF9U-10.0.2.2-ExtendedSampleClient 'ExtendedSampleClient'
D (MqttAndroidClient.kt:1122): .storeToken(): info.mqtt.android.service.MqttTokenAndroid@2270a84 size=2
V (MqttAndroidClient.kt:891): .onReceive(): OK Bundle[{.invocationContext=null, .activityToken=info.mqtt.android.service.MqttTokenAndroid@2270a84, .callbackAction=connect, .clientHandle=tcp://10.0.2.2:1883:ExtendedSampleClient:info.mqtt.android.extsample, .callbackStatus=OK}]
D (MqttAndroidClient.kt:1138): .removeMqttToken(): search=info.mqtt.android.service.MqttTokenAndroid@2270a84 1
I (ActionListener.kt:85): .connect(): PWI2XF9U-10.0.2.2-ExtendedSampleClient connected.
I (ActionListener.kt:88): .connect(): Auto-subscribing to: AnotherTest @ QoS: AtMostOnce
D (MqttAndroidClient.kt:1122): .storeToken(): info.mqtt.android.service.MqttTokenAndroid@69ec04e size=2
V (MqttAndroidClient.kt:891): .onReceive(): OK Bundle[{.invocationContext=null, .activityToken=info.mqtt.android.service.MqttTokenAndroid@69ec04e, .callbackAction=subscribe, .clientHandle=tcp://10.0.2.2:1883:ExtendedSampleClient:info.mqtt.android.extsample, .callbackStatus=OK}]
D (MqttAndroidClient.kt:1138): .removeMqttToken(): search=info.mqtt.android.service.MqttTokenAndroid@69ec04e 1
D (PublishFragment.kt:57): .onCreateView$lambda$3(): Publishing: [topic: AnotherTest, message: Typed message, QoS: AtMostOnce, Retain: false]
D (MqttAndroidClient.kt:1122): .storeToken(): info.mqtt.android.service.MqttDeliveryTokenAndroid@66e8b26 size=2
V (MqttAndroidClient.kt:891): .onReceive(): OK Bundle[{destinationName=AnotherTest, messageId=null, .invocationContext=null, .PARCEL=Typed message, .activityToken=info.mqtt.android.service.MqttDeliveryTokenAndroid@66e8b26, .callbackAction=messageDelivered, .clientHandle=tcp://10.0.2.2:1883:ExtendedSampleClient:info.mqtt.android.extsample, .callbackStatus=OK}]
D (MqttAndroidClient.kt:1138): .removeMqttToken(): search=info.mqtt.android.service.MqttDeliveryTokenAndroid@66e8b26 1
V (MqttAndroidClient.kt:891): .onReceive(): OK Bundle[{destinationName=AnotherTest, messageId=0479b087-af73-442f-9f7c-d5b4ee7dd944, .PARCEL=Typed message, .callbackAction=messageArrived, .clientHandle=tcp://10.0.2.2:1883:ExtendedSampleClient:info.mqtt.android.extsample, .callbackStatus=OK}]
I (MqttCallbackHandler.kt:55): .messageArrived(): 'Typed message' AnotherTest qos=0 retained:false
D (MessagesFragment.kt:35): .onViewCreated(): CONNECTION_KEY=PWI2XF9U-10.0.2.2-ExtendedSampleClient 'ExtendedSampleClient'
D (MqttAndroidClient.kt:1122): .storeToken(): info.mqtt.android.service.MqttTokenAndroid@492094b size=2
V (MqttAndroidClient.kt:891): .onReceive(): OK Bundle[{.invocationContext=null, .activityToken=info.mqtt.android.service.MqttTokenAndroid@492094b, .callbackAction=connect, .clientHandle=tcp://10.0.2.2:1883:ExtendedSampleClient:info.mqtt.android.extsample, .callbackStatus=OK}]
D (MqttAndroidClient.kt:1138): .removeMqttToken(): search=info.mqtt.android.service.MqttTokenAndroid@492094b 1
I (ActionListener.kt:85): .connect(): PWI2XF9U-10.0.2.2-ExtendedSampleClient connected.
I (ActionListener.kt:88): .connect(): Auto-subscribing to: AnotherTest @ QoS: AtMostOnce
D (MqttAndroidClient.kt:1122): .storeToken(): info.mqtt.android.service.MqttTokenAndroid@522f017 size=2
V (MqttAndroidClient.kt:891): .onReceive(): OK Bundle[{.invocationContext=null, .activityToken=info.mqtt.android.service.MqttTokenAndroid@522f017, .callbackAction=subscribe, .clientHandle=tcp://10.0.2.2:1883:ExtendedSampleClient:info.mqtt.android.extsample, .callbackStatus=OK}]
D (MqttAndroidClient.kt:1138): .removeMqttToken(): search=info.mqtt.android.service.MqttTokenAndroid@522f017 1
I ViewInteraction: Performing 'wait until: ListView should have > 0 items' action on view view.getId() is <2131230944/info.mqtt.android.extsample:id/history_list_view>
D (WaitingAssertion.kt:42): $Companion$waitForMatcher.perform(): false || false loopCount=0 within 2497 ms
I ViewInteraction: Checking 'MatchesViewAssertion{viewMatcher=ListView should have > 0 items}' assertion on view view.getId() is <2131230944/info.mqtt.android.extsample:id/history_list_view>
I ViewInteraction: Performing 'capture view to image' action on view view.getRootView() to equal view
I View.captureToImage: Could not find window for view. Falling back to View#draw instead of PixelCopy
D LifecycleMonitor: Lifecycle status change: info.mqtt.android.extsample.MainActivity@d8ca1e6 in: PAUSED
V ActivityScenario: Update currentActivityStage to PAUSED, currentActivity=info.mqtt.android.extsample.MainActivity@d8ca1e6
I ActivityTaskManager: Displayed info.mqtt.android.extsample.test/androidx.test.core.app.InstrumentationActivityInvoker$EmptyActivity for user 0: +319ms
D OneSearchSuggestProvider: Created the binder channel successfully for end point service =com.google.android.apps.search.googleapp.search.suggest.plugins.onesearch.server.OneSearchSuggestService , mChannel=z1{delegate=s1{logId=17, target=directaddress:///AndroidComponentAddress%5BIntent%20%7B%20act=grpc.io.action.BIND%20cmp=com.google.android.googlequicksearchbox/com.google.android.apps.search.googleapp.search.suggest.plugins.onesearch.server.OneSearchSuggestService%20%7D%5D}} , mOneSearchConnection=X1.T@c315826
D LifecycleMonitor: Lifecycle status change: info.mqtt.android.extsample.MainActivity@d8ca1e6 in: STOPPED
V ActivityScenario: Update currentActivityStage to STOPPED, currentActivity=info.mqtt.android.extsample.MainActivity@d8ca1e6
D LifecycleMonitor: Lifecycle status change: info.mqtt.android.extsample.MainActivity@d8ca1e6 in: DESTROYED
V ActivityScenario: Update currentActivityStage to DESTROYED, currentActivity=null
I TestRunner: finished: connectWaitAndPublish(info.mqtt.android.extsample.activity.ExtendedPublishSleepTest)

[github-actions]

Screenshot differs:14 7

ExtendedPublishTest_connectAndPublish-0ShowConnection.png

ExtendedPublishTest_connectAndPublish-1AddConnect.png

ExtendedPublishTest_disconnect-Before.png


../screenshotsToCompare14/view-op-error-2.png



../screenshotsToCompare14/view-op-error-1.png



../extendedSample/build/outputs/connected_android_test_additional_output/debugAndroidTest/connected/emulator-5554 - 14/view-op-error-2.png



../extendedSample/build/outputs/connected_android_test_additional_output/debugAndroidTest/connected/emulator-5554 - 14/view-op-error-1.png