Closed Bug 1753856 Opened 2 years ago Closed 2 years ago

Intermittent linux ccov /webdriver/tests/bidi/log/entry_added/event_buffer.py | test_console_log_cached_messages[console_api_log] - assert 10 == 2

Categories

(Remote Protocol :: Marionette, defect, P5)

defect

Tracking

(firefox-esr91 unaffected, firefox97 unaffected, firefox98 wontfix, firefox99 fixed)

RESOLVED FIXED
99 Branch
Tracking Status
firefox-esr91 --- unaffected
firefox97 --- unaffected
firefox98 --- wontfix
firefox99 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: jdescottes)

References

Details

(Keywords: intermittent-failure)

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=366857683&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/eXzlYd6GQRWhuyay1LSHeQ/runs/0/artifacts/public/logs/live_backing.log


[task 2022-02-05T23:11:53.787Z] 23:11:53     INFO - PID 1581 | 1644102713786	geckodriver::browser	DEBUG	Browser process stopped: exit status: 0
[task 2022-02-05T23:11:53.788Z] 23:11:53     INFO - PID 1581 | 1644102713787	webdriver::server	DEBUG	<- 200 OK {"value":null}
[task 2022-02-05T23:11:53.792Z] 23:11:53     INFO - STDOUT: =================================== FAILURES ===================================
[task 2022-02-05T23:11:53.793Z] 23:11:53     INFO - STDOUT: ______________ test_console_log_cached_messages[console_api_log] _______________
[task 2022-02-05T23:11:53.797Z] 23:11:53     INFO - STDOUT: bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f50687d87f0>
[task 2022-02-05T23:11:53.797Z] 23:11:53     INFO - STDOUT: current_session = <Session 92421ada-4084-4cc3-9a84-37ca647c5355>
[task 2022-02-05T23:11:53.797Z] 23:11:53     INFO - STDOUT: wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f50800b5d90>
[task 2022-02-05T23:11:53.798Z] 23:11:53     INFO - STDOUT: inline = <function inline.<locals>.inline at 0x7f50800b5730>
[task 2022-02-05T23:11:53.798Z] 23:11:53     INFO - STDOUT: log_type = 'console_api_log'
[task 2022-02-05T23:11:53.798Z] 23:11:53     INFO - STDOUT:     @pytest.mark.asyncio
[task 2022-02-05T23:11:53.799Z] 23:11:53     INFO - STDOUT:     @pytest.mark.parametrize("log_type", ["console_api_log", "javascript_error"])
[task 2022-02-05T23:11:53.801Z] 23:11:53     INFO - STDOUT:     async def test_console_log_cached_messages(bidi_session,
[task 2022-02-05T23:11:53.801Z] 23:11:53     INFO - STDOUT:                                                current_session,
[task 2022-02-05T23:11:53.801Z] 23:11:53     INFO - STDOUT:                                                wait_for_event,
[task 2022-02-05T23:11:53.801Z] 23:11:53     INFO - STDOUT:                                                inline,
[task 2022-02-05T23:11:53.802Z] 23:11:53     INFO - STDOUT:                                                log_type):
[task 2022-02-05T23:11:53.802Z] 23:11:53     INFO - STDOUT:         # Unsubscribe in case previous tests subscribed to log.entryAdded
[task 2022-02-05T23:11:53.803Z] 23:11:53     INFO - STDOUT:         await bidi_session.session.unsubscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.804Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.804Z] 23:11:53     INFO - STDOUT:         # Refresh to make sure no events are cached for the current window global
[task 2022-02-05T23:11:53.805Z] 23:11:53     INFO - STDOUT:         # from previous tests.
[task 2022-02-05T23:11:53.805Z] 23:11:53     INFO - STDOUT:         current_session.refresh()
[task 2022-02-05T23:11:53.806Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.806Z] 23:11:53     INFO - STDOUT:         # Log a message before subscribing
[task 2022-02-05T23:11:53.807Z] 23:11:53     INFO - STDOUT:         expected_text = create_log(current_session, inline, log_type, "cached_message")
[task 2022-02-05T23:11:53.807Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.808Z] 23:11:53     INFO - STDOUT:         # Track all received log.entryAdded events in the events array
[task 2022-02-05T23:11:53.809Z] 23:11:53     INFO - STDOUT:         events = []
[task 2022-02-05T23:11:53.809Z] 23:11:53     INFO - STDOUT:         async def on_event(method, data):
[task 2022-02-05T23:11:53.811Z] 23:11:53     INFO - STDOUT:             events.append(data)
[task 2022-02-05T23:11:53.811Z] 23:11:53     INFO - STDOUT:         remove_listener = bidi_session.add_event_listener("log.entryAdded", on_event)
[task 2022-02-05T23:11:53.812Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.813Z] 23:11:53     INFO - STDOUT:         # Subscribe
[task 2022-02-05T23:11:53.813Z] 23:11:53     INFO - STDOUT:         on_entry_added = wait_for_event("log.entryAdded")
[task 2022-02-05T23:11:53.813Z] 23:11:53     INFO - STDOUT:         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.814Z] 23:11:53     INFO - STDOUT:         await on_entry_added
[task 2022-02-05T23:11:53.815Z] 23:11:53     INFO - STDOUT:         assert len(events) == 1;
[task 2022-02-05T23:11:53.815Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.816Z] 23:11:53     INFO - STDOUT:         # Check the log.entryAdded event received has the expected properties.
[task 2022-02-05T23:11:53.817Z] 23:11:53     INFO - STDOUT:         assert_base_entry(events[0], text=expected_text)
[task 2022-02-05T23:11:53.817Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.818Z] 23:11:53     INFO - STDOUT:         # Unsubscribe and re-subscribe
[task 2022-02-05T23:11:53.819Z] 23:11:53     INFO - STDOUT:         await bidi_session.session.unsubscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.820Z] 23:11:53     INFO - STDOUT:         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.820Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.821Z] 23:11:53     INFO - STDOUT:         # Wait for some time to catch all messages.
[task 2022-02-05T23:11:53.822Z] 23:11:53     INFO - STDOUT:         time.sleep(0.5)
[task 2022-02-05T23:11:53.822Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.823Z] 23:11:53     INFO - STDOUT:         # Check that the cached event was not re-emitted.
[task 2022-02-05T23:11:53.824Z] 23:11:53     INFO - STDOUT:         assert len(events) == 1;
[task 2022-02-05T23:11:53.824Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.825Z] 23:11:53     INFO - STDOUT:         on_entry_added = wait_for_event("log.entryAdded")
[task 2022-02-05T23:11:53.826Z] 23:11:53     INFO - STDOUT:         expected_text = create_log(current_session, inline, log_type, "live_message")
[task 2022-02-05T23:11:53.826Z] 23:11:53     INFO - STDOUT:         await on_entry_added
[task 2022-02-05T23:11:53.827Z] 23:11:53     INFO - STDOUT:     
[task 2022-02-05T23:11:53.835Z] 23:11:53     INFO - 
[task 2022-02-05T23:11:53.835Z] 23:11:53     INFO - TEST-UNEXPECTED-FAIL | /webdriver/tests/bidi/log/entry_added/event_buffer.py | test_console_log_cached_messages[console_api_log] - assert 10 == 2
[task 2022-02-05T23:11:53.835Z] 23:11:53     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f50687d87f0>
[task 2022-02-05T23:11:53.835Z] 23:11:53     INFO - current_session = <Session 92421ada-4084-4cc3-9a84-37ca647c5355>
[task 2022-02-05T23:11:53.835Z] 23:11:53     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f50800b5d90>
[task 2022-02-05T23:11:53.835Z] 23:11:53     INFO - inline = <function inline.<locals>.inline at 0x7f50800b5730>
[task 2022-02-05T23:11:53.836Z] 23:11:53     INFO - log_type = 'console_api_log'
[task 2022-02-05T23:11:53.836Z] 23:11:53     INFO - 
[task 2022-02-05T23:11:53.836Z] 23:11:53     INFO -     @pytest.mark.asyncio
[task 2022-02-05T23:11:53.836Z] 23:11:53     INFO -     @pytest.mark.parametrize("log_type", ["console_api_log", "javascript_error"])
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -     async def test_console_log_cached_messages(bidi_session,
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -                                                current_session,
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -                                                wait_for_event,
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -                                                inline,
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -                                                log_type):
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -         # Unsubscribe in case previous tests subscribed to log.entryAdded
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -         await bidi_session.session.unsubscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -         # Refresh to make sure no events are cached for the current window global
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -         # from previous tests.
[task 2022-02-05T23:11:53.837Z] 23:11:53     INFO -         current_session.refresh()
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -         # Log a message before subscribing
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -         expected_text = create_log(current_session, inline, log_type, "cached_message")
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -         # Track all received log.entryAdded events in the events array
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -         events = []
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -         async def on_event(method, data):
[task 2022-02-05T23:11:53.838Z] 23:11:53     INFO -             events.append(data)
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         remove_listener = bidi_session.add_event_listener("log.entryAdded", on_event)
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         # Subscribe
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         on_entry_added = wait_for_event("log.entryAdded")
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         await on_entry_added
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         assert len(events) == 1;
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         # Check the log.entryAdded event received has the expected properties.
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         assert_base_entry(events[0], text=expected_text)
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         # Unsubscribe and re-subscribe
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         await bidi_session.session.unsubscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         await bidi_session.session.subscribe(events=["log.entryAdded"])
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         # Wait for some time to catch all messages.
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         time.sleep(0.5)
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         # Check that the cached event was not re-emitted.
[task 2022-02-05T23:11:53.841Z] 23:11:53     INFO -         assert len(events) == 1;
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -         on_entry_added = wait_for_event("log.entryAdded")
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -         expected_text = create_log(current_session, inline, log_type, "live_message")
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -         await on_entry_added
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -     
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -         # Check that we only received the live message.
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - >       assert len(events) == 2;
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - E       assert 10 == 2
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - E         +10
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - E         -2
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - 
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - bidi_session = <webdriver.bidi.client.BidiSession object at 0x7f50687d87f0>
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - current_session = <Session 92421ada-4084-4cc3-9a84-37ca647c5355>
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO - events     = [{'args': [{'type': 'string', 'value': 'cached_message'}],
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'level': 'info',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'method': 'log',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'text': 'cached_message',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'timestamp': 1644102708865,
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -  {'args': [{'type': 'string',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -             'value': 'Registering redirect listener for requestStorageAccess '
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -                      'helper'},
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'text': 'Registering redirect listener for requestStorageAccess helper '
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -           '[object Object]',
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'timestamp': 1644102709187,
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Allowing access to these logos:'},
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.842Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'text': 'Allowing access to these logos: '
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -           'https://smartblock.firefox.etp/facebook.svg,https://smartblock.firefox.etp/play.svg',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'timestamp': 1644102709189,
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Shimming these'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -            {'type': 'string', 'value': 'sub_frame'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -            {'type': 'string', 'value': 'URLs:'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'text': 'Shimming these sub_frame URLs: *://trends.google.com/trends/embed*',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'timestamp': 1644102709190,
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Shimming these'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -            {'type': 'string', 'value': 'script'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -            {'type': 'string', 'value': 'URLs:'},
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'text': 'Shimming these script URLs: '
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -           '*://webcompat-addon-testbed.herokuapp.com/shims_test.js,*://example.com/browser/browser/extensions/webcompat/tests/browser/shims_test.js,*://example.com/browser/browser/extensions/webcompat/tests/browser/shims_test_2.js,*://example.com/browser/browser/extensions/webcompat/tests/browser/shims_test_3.js,*://s7.addthis.com/icons/official-addthis-angularjs/current/dist/official-addthis-angularjs.min.js*,*://track.adform.net/serving/scripts/trackpoint/,*://track.adform.net/serving/scripts/trackpoint/async/,*://acdn.adnxs.com/ast/ast.js,*://*.adnxs.com/*/pb.js,*://*.adnxs.com/*/prebid,*://static.adsafeprotected.com/vans-adapter-google-ima.js,*://pagead2.googlesyndication.com/pagead/js/adsbygoogle.js,*://c.amazon-adsystem.com/aax2/apstag.js,*://auth.9c9media.ca/auth/main.js,*://static.chartbeat.com/js/chartbeat.js,*://static.chartbeat.com/js/chartbeat_video.js,*://static.criteo.net/js/ld/publishertag.js,*://libs.coremetrics.com/eluminate.js,*://connect.facebook.net/*/sdk.js*,*://connect.facebook.net/*/all.js*,*://www.google-analytics.com/analytics.js*,*://www.google-analytics.com/gtm/js*,*://www.googletagmanager.com/gtm.js*,*://www.google-analytics.com/plugins/ua/ec.js,*://ssl.google-analytics.com/ga.js,*://s0.2mdn.net/instream/html5/ima3.js,*://imasdk.googleapis.com/js/sdkloader/ima3.js,*://www.googleadservices.com/pagead/conversion_async.js,*://www.googletagservices.com/tag/js/gpt.js*,*://securepubads.g.doubleclick.net/tag/js/gpt.js*,*://securepubads.g.doubleclick.net/gpt/pubads_impl_*.js*,*://cdn.adsafeprotected.com/iasPET.1.js,*://adservex.media.net/videoAds.js*,*://*.moatads.com/*/moatad.js*,*://*.moatads.com/*/moatapi.js*,*://*.moatads.com/*/moatheader.js*,*://*.moatads.com/*/yi.js*,*://cdn.optimizely.com/js/*.js,*://id.rambler.ru/rambler-id-helper/auth_events.js,*://media.richrelevance.com/rrserver/js/1.2/p13n.js,*://*.vidible.tv/*/vidible-min.js*,*://vdb-cdn-files.s3.amazonaws.com/*/vidible-min.js*',
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'timestamp': 1644102709191,
[task 2022-02-05T23:11:53.843Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Shimming these'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            {'type': 'string', 'value': 'image'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            {'type': 'string', 'value': 'URLs:'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'text': 'Shimming these image URLs: '
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -           '*://track.adform.net/Serving/TrackPoint/*,*://securepubads.g.doubleclick.net/gampad/*ad-blk*,*://pubads.g.doubleclick.net/gampad/*ad-blk*,*://securepubads.g.doubleclick.net/gampad/*xml_vmap1*,*://pubads.g.doubleclick.net/gampad/*xml_vmap1*,*://securepubads.g.doubleclick.net/gampad/*xml_vmap2*,*://pubads.g.doubleclick.net/gampad/*xml_vmap2*,*://securepubads.g.doubleclick.net/gampad/*ad*,*://pubads.g.doubleclick.net/gampad/*ad*,*://www.facebook.com/platform/impression.php*',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'timestamp': 1644102709193,
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Shimming these'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            {'type': 'string', 'value': 'imageset'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            {'type': 'string', 'value': 'URLs:'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'text': 'Shimming these imageset URLs: '
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -           '*://track.adform.net/Serving/TrackPoint/*,*://securepubads.g.doubleclick.net/gampad/*ad-blk*,*://pubads.g.doubleclick.net/gampad/*ad-blk*,*://securepubads.g.doubleclick.net/gampad/*xml_vmap1*,*://pubads.g.doubleclick.net/gampad/*xml_vmap1*,*://securepubads.g.doubleclick.net/gampad/*xml_vmap2*,*://pubads.g.doubleclick.net/gampad/*xml_vmap2*,*://securepubads.g.doubleclick.net/gampad/*ad*,*://pubads.g.doubleclick.net/gampad/*ad*,*://www.facebook.com/platform/impression.php*',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'timestamp': 1644102709193,
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Shimming these'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            {'type': 'string', 'value': 'xmlhttprequest'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            {'type': 'string', 'value': 'URLs:'},
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.844Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -   'text': 'Shimming these xmlhttprequest URLs: '
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -           '*://track.adform.net/Serving/TrackPoint/*,*://securepubads.g.doubleclick.net/gampad/*ad-blk*,*://pubads.g.doubleclick.net/gampad/*ad-blk*,*://securepubads.g.doubleclick.net/gampad/*xml_vmap1*,*://pubads.g.doubleclick.net/gampad/*xml_vmap1*,*://securepubads.g.doubleclick.net/gampad/*xml_vmap2*,*://pubads.g.doubleclick.net/gampad/*xml_vmap2*,*://securepubads.g.doubleclick.net/gampad/*ad*,*://pubads.g.doubleclick.net/gampad/*ad*,*://www.facebook.com/platform/impression.php*',
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -   'timestamp': 1644102709194,
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'Shimming these'},
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -            {'type': 'string', 'value': 'sub_frame'},
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -            {'type': 'string', 'value': 'URLs:'},
[task 2022-02-05T23:11:53.845Z] 23:11:53     INFO -            None],
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -   'level': 'debug',
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -   'method': 'debug',
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -   'text': 'Shimming these sub_frame URLs: '
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -           '*://tpc.googlesyndication.com/safeframe/*/html/container.html,*://*.safeframe.googlesyndication.com/safeframe/*/html/container.html',
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -   'timestamp': 1644102709195,
[task 2022-02-05T23:11:53.846Z] 23:11:53     INFO -   'type': 'console'},
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -  {'args': [{'type': 'string', 'value': 'live_message'}],
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -   'level': 'info',
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -   'method': 'log',
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -   'realm': None,
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -   'text': 'live_message',
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -   'timestamp': 1644102709425,
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO -   'type': 'console'}]
[task 2022-02-05T23:11:53.847Z] 23:11:53     INFO - expected_text = 'live_message'
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - inline     = <function inline.<locals>.inline at 0x7f50800b5730>
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - log_type   = 'console_api_log'
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - on_entry_added = <Future finished result={'args': [{'type': 'string', 'value': 'Registering ...Access helper'}, None], 'level': 'debug', 'method': 'debug', 'realm': None, ...}>
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - on_event   = <function test_console_log_cached_messages.<locals>.on_event at 0x7f50800b5598>
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - remove_listener = <function BidiSession.add_event_listener.<locals>.<lambda> at 0x7f50800b5a60>
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - wait_for_event = <function wait_for_event.<locals>.wait_for_event at 0x7f50800b5d90>
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - 
[task 2022-02-05T23:11:53.848Z] 23:11:53     INFO - tests/web-platform/tests/webdriver/tests/bidi/log/entry_added/event_buffer.py:53: AssertionError
Assignee: nobody → jdescottes
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 99 Branch
Moving bug to Remote Protocol::Marionette component per bug 1815831.
Component: geckodriver → Marionette
Product: Testing → Remote Protocol
You need to log in before you can comment on or make changes to this bug.