Categories
(Testing :: Condprofile, defect, P5)
Tracking
(Not tracked)
People
(Reporter: intermittent-bug-filer, Assigned: tarek)
Details
(Keywords: intermittent-failure)
Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=320682719&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FZ435_uhTf66h89JQg37LA/runs/0/artifacts/public/logs/live_backing.log
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Running command: ['/builds/task_160447547852202/workspace/build/venv/bin/python', u'--version']
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Copy/paste: /builds/task_160447547852202/workspace/build/venv/bin/python --version
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Python 2.7.17
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Return code: 0
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/task_160447547852202/workspace/build/blobber_upload_dir
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - ENV: RUST_BACKTRACE is now full
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/task_160447547852202/fetches/minidump_stackwalk/minidump_stackwalk
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/task_160447547852202/workspace/build/blobber_upload_dir
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - adb -s HT86B1A00942 logcat -v threadtime Trace:S StrictMode:S ExchangeService:S
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Running command: ['/builds/task_160447547852202/workspace/build/venv/bin/python', u'/builds/task_160447547852202/workspace/build/tests/raptor/raptor/raptor.py', u'--chimera', u'--browsertime-node', '/builds/task_160447547852202/fetches/node/bin/node', u'--browsertime-browsertimejs', '/builds/task_160447547852202/fetches/browsertime/node_modules/browsertime/bin/browsertime.js', u'--browsertime-chromedriver', '/builds/task_160447547852202/fetches/{}chromedriver', u'--browsertime-ffmpeg', '/builds/task_160447547852202/fetches/ffmpeg-4.1.4-i686-static/ffmpeg', u'--browsertime-geckodriver', '/builds/task_160447547852202/fetches/geckodriver', u'--browsertime-video', u'--browsertime-no-ffwindowrecorder', u'--browsertime', u'--installerpath', u'/builds/task_160447547852202/workspace/build/geckoview_example.apk', u'--binary', 'org.mozilla.geckoview_example', u'--device-name', 'p2_aarch64', u'--app', 'geckoview', u'--activity', 'org.mozilla.geckoview_example.GeckoViewActivity', u'--conditioned-profile-scenario', u'settled', u'--host', u'127.0.0.1', u'--symbolsPath', u'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/en-US/target.crashreporter-symbols.zip', u'--test', 'wikipedia', u'--log-tbpl-level=debug'] in /builds/task_160447547852202/workspace/build
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Copy/paste: /builds/task_160447547852202/workspace/build/venv/bin/python /builds/task_160447547852202/workspace/build/tests/raptor/raptor/raptor.py --chimera --browsertime-node /builds/task_160447547852202/fetches/node/bin/node --browsertime-browsertimejs /builds/task_160447547852202/fetches/browsertime/node_modules/browsertime/bin/browsertime.js --browsertime-chromedriver /builds/task_160447547852202/fetches/{}chromedriver --browsertime-ffmpeg /builds/task_160447547852202/fetches/ffmpeg-4.1.4-i686-static/ffmpeg --browsertime-geckodriver /builds/task_160447547852202/fetches/geckodriver --browsertime-video --browsertime-no-ffwindowrecorder --browsertime --installerpath /builds/task_160447547852202/workspace/build/geckoview_example.apk --binary org.mozilla.geckoview_example --device-name p2_aarch64 --app geckoview --activity org.mozilla.geckoview_example.GeckoViewActivity --conditioned-profile-scenario settled --host 127.0.0.1 --symbolsPath https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/en-US/target.crashreporter-symbols.zip --test wikipedia --log-tbpl-level=debug
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - Using env: {'ANDROID_DEVICE': 'HT86B1A00942',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'DEBUG': '*',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'DEVICE_IP': '10.7.205.32',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'DEVICE_NAME': 'pixel2-58',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'DEVICE_SERIAL': 'HT86B1A00942',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'DOCKER_IMAGE_VERSION': '',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'EXTERNALTOOLSPATH': u'/builds/task_160447547852202/workspace/mozharness/external_tools',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'EXTRA_MOZHARNESS_CONFIG': '{"installer_url": "https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/geckoview_example.apk", "test_packages_url": "https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/en-US/target.test_packages.json"}',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'GECKO_HEAD_REPOSITORY': 'https://hg.mozilla.org/mozilla-central',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'GECKO_HEAD_REV': 'ad4d5535cb4fef596e43e4e21f43c04e66c6828d',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'HOME': '/builds/worker',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'HOSTNAME': 'bitbar-ubuntu-173',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'HOSTUTILS_MANIFEST_PATH': 'testing/config/tooltool-manifests/linux64/hostutils.manifest',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'HOST_IP': '10.7.205.173',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'JSGC_DISABLE_POISONING': u'1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'MINIDUMP_SAVE_PATH': u'/builds/task_160447547852202/workspace/build/blobber_upload_dir',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'MINIDUMP_STACKWALK': '/builds/task_160447547852202/fetches/minidump_stackwalk/minidump_stackwalk',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZHARNESS_CONFIG': 'raptor/android_hw_config.py',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZHARNESS_SCRIPT': 'raptor_script.py',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZHARNESS_URL': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/mozharness.zip',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZILLA_BUILD_URL': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/geckoview_example.apk',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_AUTOMATION': '1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_FETCHES': '[{"artifact": "public/build/browsertime.tar.bz2", "extract": true, "task": "X-4NVxLSTjGiNQu1ytBWAQ"}, {"artifact": "public/build/fix-stacks.tar.xz", "extract": true, "task": "RX2sctxUT_mg18G9K3Squw"}, {"artifact": "public/build/geckodriver.tar.gz", "extract": true, "task": "Y7Ee6L5KTUWwIT8FCgj0fQ"}, {"artifact": "public/build/minidump_stackwalk.tar.xz", "extract": true, "task": "PZ5dyUnuRPCjr__efbsClQ"}, {"artifact": "public/build/node.tar.xz", "extract": true, "task": "eBhb5JiJRuK6L9J0A9IP1Q"}, {"artifact": "public/ffmpeg-4.1.4-i686-static.tar.xz", "extract": true, "task": "AzMGU1R4STegd5jDnPTXGQ"}]',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_FETCHES_DIR': '/builds/task_160447547852202/fetches',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_HIDE_RESULTS_TABLE': '1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_NODE_PATH': '/usr/local/bin/node',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_NO_REMOTE': '1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_SCM_LEVEL': '3',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_SOURCE_CHANGESET': 'ad4d5535cb4fef596e43e4e21f43c04e66c6828d',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'MOZ_SOURCE_REPO': 'https://hg.mozilla.org/mozilla-central',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'MOZ_UPLOAD_DIR': u'/builds/task_160447547852202/workspace/build/blobber_upload_dir',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'NEED_XVFB': 'false',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'NO_FAIL_ON_TEST_ERRORS': '1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'OLDPWD': '/builds/task_160447547852202/workspace',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'PATH': '/builds/task_160447547852202/workspace/build/venv/bin:/builds/task_160447547852202/workspace/build/venv/bin:/builds/task_160447547852202/workspace/build/venv/bin:/builds/worker/.pyenv/shims:/builds/worker/.pyenv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/builds/worker/bin:/usr/local/bin:/builds/worker/android-sdk-linux/platform-tools:/builds/worker/android-sdk-linux/platform-tools/bin:/builds/worker/android-sdk-linux/tools:/builds/worker/android-sdk-linux/tools/bin:/builds/worker/android-sdk-linux/build-tools/27.0.3:/builds/worker/android-sdk-linux/tools/bin:/builds/worker/android-sdk-linux/platform-tools',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'PWD': '/builds/task_160447547852202/workspace',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'PYTHONIOENCODING': 'utf-8',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'PYTHONPATH': u'/builds/task_160447547852202/workspace/build/tests/raptor',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'RUN_ID': '0',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'RUST_BACKTRACE': u'full',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'SCCACHE_DISABLE': '1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - u'SCRIPTSPATH': '/builds/task_160447547852202/workspace/mozharness',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'SHLVL': '1',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TASKCLUSTER_PROXY_URL': 'http://localhost:8099',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TASKCLUSTER_ROOT_URL': 'https://firefox-ci-tc.services.mozilla.com',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TASKCLUSTER_WORKER_TYPE': 't-bitbar-gw-perf-p2',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TASK_ID': 'FZ435_uhTf66h89JQg37LA',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TC_WORKER_GROUP': 'bitbar',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TC_WORKER_TYPE': 'gecko-t-bitbar-gw-perf-p2',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TESTDROID_BUILD_ID': '2341427',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TESTDROID_PROJECT_ID': '2200086',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TESTDROID_RUN_ID': '63939977',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'TOOLTOOLCACHE': '/builds/tooltool_cache',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'USER': 'root',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'VIRTUAL_ENV': '/builds/task_160447547852202/workspace/build/venv',
[task 2020-11-04T07:39:49.582Z] 07:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn'}
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - Calling ['/builds/task_160447547852202/workspace/build/venv/bin/python', u'/builds/task_160447547852202/workspace/build/tests/raptor/raptor/raptor.py', u'--chimera', u'--browsertime-node', '/builds/task_160447547852202/fetches/node/bin/node', u'--browsertime-browsertimejs', '/builds/task_160447547852202/fetches/browsertime/node_modules/browsertime/bin/browsertime.js', u'--browsertime-chromedriver', '/builds/task_160447547852202/fetches/{}chromedriver', u'--browsertime-ffmpeg', '/builds/task_160447547852202/fetches/ffmpeg-4.1.4-i686-static/ffmpeg', u'--browsertime-geckodriver', '/builds/task_160447547852202/fetches/geckodriver', u'--browsertime-video', u'--browsertime-no-ffwindowrecorder', u'--browsertime', u'--installerpath', u'/builds/task_160447547852202/workspace/build/geckoview_example.apk', u'--binary', 'org.mozilla.geckoview_example', u'--device-name', 'p2_aarch64', u'--app', 'geckoview', u'--activity', 'org.mozilla.geckoview_example.GeckoViewActivity', u'--conditioned-profile-scenario', u'settled', u'--host', u'127.0.0.1', u'--symbolsPath', u'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/en-US/target.crashreporter-symbols.zip', u'--test', 'wikipedia', u'--log-tbpl-level=debug'] with output_timeout 3600
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-main Info: Python version: 2.7.17 (default, Jul 20 2020, 15:37:01)
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - [GCC 7.5.0]
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-main Info: raptor-start
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-main Info: received command line arguments: Namespace(activity='org.mozilla.geckoview_example.GeckoViewActivity', app='geckoview', binary='org.mozilla.geckoview_example', browser_cycles=None, browsertime=True, browsertime_browsertimejs='/builds/task_160447547852202/fetches/browsertime/node_modules/browsertime/bin/browsertime.js', browsertime_chromedriver='/builds/task_160447547852202/fetches/{}chromedriver', browsertime_ffmpeg='/builds/task_160447547852202/fetches/ffmpeg-4.1.4-i686-static/ffmpeg', browsertime_geckodriver='/builds/task_160447547852202/fetches/geckodriver', browsertime_no_ffwindowrecorder=True, browsertime_node='/builds/task_160447547852202/fetches/node/bin/node', browsertime_video=True, browsertime_vismet_script=None, browsertime_visualmetrics=False, chimera=True, cold=True, conditioned_profile_scenario='settled', cpu_test=False, debug_mode=False, device_name='p2_aarch64', disable_perf_tuning=False, e10s=True, enable_fission=False, enable_webrender=False, environment={}, extra_prefs={}, gecko_profile=False, gecko_profile_entries=None, gecko_profile_interval=None, gecko_profile_threads=None, host='127.0.0.1', installerpath='/builds/task_160447547852202/workspace/build/geckoview_example.apk', intent='android.intent.action.MAIN', is_release_build=False, live_sites=False, log_errorsummary=None, log_grouped=None, log_html=None, log_mach=None, log_mach_buffer=None, log_mach_level=None, log_mach_screenshot=None, log_mach_verbose=None, log_raw=None, log_raw_level=None, log_tbpl=None, log_tbpl_buffer=None, log_tbpl_compact=None, log_tbpl_level='debug', log_unittest=None, log_xunit=None, memory_test=False, no_conditioned_profile=False, noinstall=False, obj_path=None, page_cycles=2, page_timeout=None, post_startup_delay=30000, power_test=False, project='mozilla-central', run_local=False, symbols_path='https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/en-US/target.crashreporter-symbols.zip', test='wikipedia', test_url_params=None, verbose=False)
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-manifest Info: /builds/task_160447547852202/workspace/build/tests/raptor/raptor/raptor.ini
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-manifest Info: configuring settings for test wikipedia
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-manifest Info: setting page-cycles to 2 as specified on cmd line
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-main Info: raptor tests scheduled to run:
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-main Info: wikipedia
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Info: Using a conditioned profile.
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - mozversion Unable to find application.ini
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - mozversion Unable to find platform.ini
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Warning: Could not find a browser name
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Warning: Could not find a browser version
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - mozversion Unable to find application.ini
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - mozversion Unable to find platform.ini
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Warning: Could not find a browser name
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Warning: Could not find a browser version
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Info: Post startup delay set to 1000 ms
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Info: main raptor init, config is: {'verbose': False, 'e10s': True, 'app': 'geckoview', 'symbols_path': 'https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/FtyYAqyWTOyCESOrRRfxrA/artifacts/public/build/en-US/target.crashreporter-symbols.zip', 'power_test': False, 'run_local': False, 'chimera': True, 'cold': True, 'gecko_profile_interval': None, 'binary': 'org.mozilla.geckoview_example', 'enable_control_server_wait': False, 'no_conditioned_profile': False, 'environment': {}, 'platform': 'linux', 'conditioned_profile_scenario': 'settled', 'extra_prefs': {}, 'gecko_profile': False, 'gecko_profile_entries': None, 'disable_perf_tuning': False, 'host': '127.0.0.1', 'enable_fission': False, 'memory_test': False, 'enable_webrender': False, 'obj_path': None, 'live_sites': False, 'device_name': 'p2_aarch64', 'project': 'mozilla-central', 'cpu_test': False, 'is_release_build': False, 'using_condprof': True, 'processor': 'x86_64'}
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Info: Making temp_download_dir from inside get_conditioned_profile /tmp/tmpJbjwAn
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Info: Platform used: p2_aarch64-geckoview_example
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - raptor-perftest Info: Getting profile from project mozilla-central
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - condprof INFO | Getting conditioned profile with arguments: {'repo': 'mozilla-central', 'platform': 'p2_aarch64-geckoview_example', 'task_id': None, 'customization': 'default', 'scenario': 'settled'}
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - condprof INFO | Response code is 200
[task 2020-11-04T07:44:32.848Z] 07:39:49 INFO - condprof INFO | preparing download dir
[task 2020-11-04T07:44:32.849Z] 07:39:49 INFO - condprof INFO | Downloaded archive path: /builds/worker/.condprof-cache/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:39:49 INFO - condprof INFO | Getting https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:39:49 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:40:20 INFO - condprof INFO | Failed to get the profile.
[task 2020-11-04T07:44:32.849Z] 07:40:20 INFO - condprof INFO | Failed, retrying
[task 2020-11-04T07:44:32.849Z] 07:41:05 INFO - condprof INFO | Getting https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:41:05 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:41:35 INFO - condprof INFO | Failed to get the profile.
[task 2020-11-04T07:44:32.849Z] 07:41:35 INFO - condprof INFO | Failed, retrying
[task 2020-11-04T07:44:32.849Z] 07:42:42 INFO - condprof INFO | Getting https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:42:42 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Response code is 500
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Checking for existence of: /builds/worker/.condprof-cache/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Could not find an existing archive.
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Content in cache directory /builds/worker/.condprof-cache: []
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Downloading https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.mozilla-central.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Failed to extract the tarball
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Removing cached file to attempt a new download
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Failed to get the profile.
[task 2020-11-04T07:44:32.849Z] 07:42:51 INFO - condprof INFO | Failed, retrying
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof ERROR | All attempt failed
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof INFO | Getting conditioned profile with arguments: {'repo': 'try', 'platform': 'p2_aarch64-geckoview_example', 'task_id': None, 'customization': 'default', 'scenario': 'settled'}
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof INFO | Response code is 200
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof INFO | preparing download dir
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof INFO | Downloaded archive path: /builds/worker/.condprof-cache/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:44:32.849Z] 07:44:32 INFO - condprof INFO | Getting https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Response code is 404
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Checking for existence of: /builds/worker/.condprof-cache/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Could not find an existing archive.
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Content in cache directory /builds/worker/.condprof-cache: ['profile-p2_aarch64-geckoview_example-settled-default.tgz.etag']
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Downloading https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Failed to extract the tarball
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Removing cached file to attempt a new download
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Failed to get the profile.
[task 2020-11-04T07:48:15.021Z] 07:44:32 INFO - condprof INFO | Failed, retrying
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Getting https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Response code is 404
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Checking for existence of: /builds/worker/.condprof-cache/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Could not find an existing archive.
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Content in cache directory /builds/worker/.condprof-cache: ['profile-p2_aarch64-geckoview_example-settled-default.tgz.etag']
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Downloading https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Failed to extract the tarball
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Removing cached file to attempt a new download
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Failed to get the profile.
[task 2020-11-04T07:48:15.021Z] 07:45:18 INFO - condprof INFO | Failed, retrying
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Getting https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Getting headers at https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Response code is 404
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Checking for existence of: /builds/worker/.condprof-cache/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Could not find an existing archive.
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Content in cache directory /builds/worker/.condprof-cache: ['profile-p2_aarch64-geckoview_example-settled-default.tgz.etag']
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Downloading https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Failed to extract the tarball
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Removing cached file to attempt a new download
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Failed to get the profile.
[task 2020-11-04T07:48:15.021Z] 07:46:25 INFO - condprof INFO | Failed, retrying
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - condprof ERROR | All attempt failed
[task 2020-11-04T07:48:15.021Z] 07:48:07 ERROR - Traceback (most recent call last):
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/raptor.py", line 142, in main
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - verbose=args.verbose,
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/raptor.py", line 107, in raptor_class
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - return klass(*inner_args, **inner_kwargs)
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/browsertime/android.py", line 48, in init
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - app, binary, profile_class="firefox", **kwargs
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/browsertime/base.py", line 57, in init
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - app, binary, results_handler_class=klass, *kwargs
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/perftest.py", line 211, in init
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - self.build_browser_profile()
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/browsertime/android.py", line 149, in build_browser_profile
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - super(BrowsertimeAndroid, self).build_browser_profile()
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/perftest.py", line 624, in build_browser_profile
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - super(PerftestAndroid, self).build_browser_profile()
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/browsertime/base.py", line 82, in build_browser_profile
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - super(Browsertime, self).build_browser_profile()
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/perftest.py", line 318, in build_browser_profile
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - self.profile_class, profile=self.get_conditioned_profile()
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/tests/raptor/raptor/perftest.py", line 280, in get_conditioned_profile
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - temp_download_dir, platform, profile_scenario, repo=alternate_repo
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - File "/builds/task_160447547852202/workspace/build/venv/lib/python2.7/site-packages/condprof/client.py", line 211, in get_profile
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - raise ProfileNotFoundError(url)
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - ProfileNotFoundError: https://firefox-ci-tc.services.mozilla.com/api/index/v1/task/gecko.v2.try.latest.firefox.condprof-p2_aarch64-geckoview_example/artifacts/public/condprof/profile-p2_aarch64-geckoview_example-settled-default.tgz
[task 2020-11-04T07:48:15.021Z] 07:48:07 ERROR - raptor-main Critical: TEST-UNEXPECTED-FAIL: could not initialize the raptor test runner
[task 2020-11-04T07:48:15.021Z] 07:48:07 ERROR - Return code: 1
[task 2020-11-04T07:48:15.021Z] 07:48:07 WARNING - setting return code to 1
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Killing logcat pid 796.
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Copying Raptor results to upload dir:
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - /builds/task_160447547852202/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Copying raptor results from /builds/task_160447547852202/workspace/build/raptor.json to /builds/task_160447547852202/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2020-11-04T07:48:15.021Z] 07:48:07 CRITICAL - Error copying results /builds/task_160447547852202/workspace/build/raptor.json to upload dir /builds/task_160447547852202/workspace/build/blobber_upload_dir/perfherder-data.json
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - [Errno 2] No such file or directory: u'/builds/task_160447547852202/workspace/build/raptor.json'
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Running post-action listener: _package_coverage_data
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Running post-action listener: _resource_record_post_action
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Running post-action listener: process_java_coverage_data
[task 2020-11-04T07:48:15.021Z] 07:48:07 INFO - Running post-action listener: stop_device
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - Killing logcat pid 796.
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - [mozharness: 2020-11-04 07:48:14.916978Z] Finished run-tests step (success)
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - Running post-run listener: _resource_record_post_run
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - Total resource usage - Wall time: 540s; CPU: 8.0%; Read bytes: 15785984; Write bytes: 777875456; Read time: 15964; Write time: 937276
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: CPU usage<br/>8.4%
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: I/O read bytes / time<br/>15,785,984 / 15,964
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: I/O write bytes / time<br/>777,875,456 / 937,276
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: CPU idle<br/>1,927.8 (90.2%)
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: CPU iowait<br/>30.5 (1.4%)
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: CPU system<br/>42.8 (2.0%)
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: CPU user<br/>132.6 (6.2%)
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - TinderboxPrint: Swap in / out<br/>122,880 / 0
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - install-chrome-android - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - install-chromium-distribution - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0
[task 2020-11-04T07:48:15.021Z] 07:48:14 INFO - install - Wall time: 34s; CPU: 8.0%; Read bytes: 8192; Write bytes: 17301504; Read time: 36; Write time: 7544
[task 2020-11-04T07:48:20.493Z] 07:48:14 INFO - run-tests - Wall time: 498s; CPU: 8.0%; Read bytes: 15605760; Write bytes: 752029696; Read time: 15840; Write time: 928524
[task 2020-11-04T07:48:20.493Z] 07:48:15 WARNING - returning nonzero exit status 1
[task 2020-11-04T07:48:20.493Z] cleanup
[task 2020-11-04T07:48:20.493Z] + cleanup
[task 2020-11-04T07:48:20.493Z] + local rv=1
[task 2020-11-04T07:48:20.493Z] + [[ -s /builds/worker/.xsession-errors ]]
[task 2020-11-04T07:48:20.493Z] + false
[task 2020-11-04T07:48:20.493Z] + '[' ']'
[task 2020-11-04T07:48:20.493Z] + exit 1
[task 2020-11-04T07:48:20.493Z] script.py: command finished
[task 2020-11-04T07:48:20.493Z] script.py: enabling charging for device 'Pixel 2' ('HT86B1A00942')...
[task 2020-11-04T07:48:20.493Z]
[task 2020-11-04T07:48:20.493Z] netstat -aop
[task 2020-11-04T07:48:20.493Z] Active Internet connections (servers and established)
[task 2020-11-04T07:48:20.493Z] Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name Timer
[task 2020-11-04T07:48:20.493Z] tcp 0 0 localhost:8099 0.0.0.0: LISTEN 55/taskcluster-prox off (0.00/0/0)
Comment hidden (Intermittent Failures Robot) |
Comment 2•4 years ago
|
||
https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.
Description
•