builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-048 starttime: 1507065505.26 results: success (0) revision: 65a5054a1f922b83929c80658062f441ca3da6a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.260713) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.261365) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.261636) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.363516) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.363920) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-10-03 14:18:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.3M=0s 2017-10-03 14:18:25 (35.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099158 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.498509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.498793) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018939 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.612531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:25.612816) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 65a5054a1f922b83929c80658062f441ca3da6a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 65a5054a1f922b83929c80658062f441ca3da6a0 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-10-03 14:18:25,971 truncating revision to first 12 chars 2017-10-03 14:18:25,972 Setting DEBUG logging. 2017-10-03 14:18:25,972 attempt 1/10 2017-10-03 14:18:25,972 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/65a5054a1f92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-10-03 14:18:26,535 unpacking tar archive at: mozilla-central-65a5054a1f92/testing/mozharness/ program finished with exit code 0 elapsedTime=0.851727 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.492573) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.492895) ========= script_repo_revision: 65a5054a1f922b83929c80658062f441ca3da6a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.493275) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.496568) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-10-03 14:18:26.530547) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-10-03 14:18:26.530821) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:18:27 INFO - MultiFileLogger online at 20171003 14:18:27 in /builds/slave/test 14:18:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 14:18:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:27 INFO - {'append_to_log': False, 14:18:27 INFO - 'base_work_dir': '/builds/slave/test', 14:18:27 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 14:18:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:27 INFO - 'branch': 'Firefox-Non-PGO', 14:18:27 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:27 INFO - 'code_coverage': False, 14:18:27 INFO - 'config_files': ('talos/linux_config.py',), 14:18:27 INFO - 'default_actions': ('clobber', 14:18:27 INFO - 'read-buildbot-config', 14:18:27 INFO - 'download-and-extract', 14:18:27 INFO - 'populate-webroot', 14:18:27 INFO - 'create-virtualenv', 14:18:27 INFO - 'install', 14:18:27 INFO - 'setup-mitmproxy', 14:18:27 INFO - 'run-tests'), 14:18:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:27 INFO - 'disable_ccov_upload': False, 14:18:27 INFO - 'disable_stylo': False, 14:18:27 INFO - 'download_minidump_stackwalk': True, 14:18:27 INFO - 'download_symbols': 'ondemand', 14:18:27 INFO - 'enable_stylo': False, 14:18:27 INFO - 'enable_webrender': False, 14:18:27 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 14:18:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:27 INFO - 'gecko_profile': False, 14:18:27 INFO - 'gecko_profile_interval': 0, 14:18:27 INFO - 'installer_path': 'installer.exe', 14:18:27 INFO - 'log_level': 'info', 14:18:27 INFO - 'log_name': 'talos', 14:18:27 INFO - 'log_to_console': True, 14:18:27 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:18:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:18:27 INFO - 'opt_config_files': (), 14:18:27 INFO - 'pip_index': False, 14:18:27 INFO - 'suite': 'dromaeojs-e10s', 14:18:27 INFO - 'system_bits': '32', 14:18:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:27 INFO - 'title': 'talos-linux64-ix-048', 14:18:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:27 INFO - 'use_talos_json': True, 14:18:27 INFO - 'verify': 'False', 14:18:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:27 INFO - 'work_dir': 'build'} 14:18:27 INFO - [mozharness: 2017-10-03 21:18:27.031740Z] Running clobber step. 14:18:27 INFO - Running pre-action listener: _resource_record_pre_action 14:18:27 INFO - Running main action method: clobber 14:18:27 INFO - rmtree: /builds/slave/test/build 14:18:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:28 INFO - Running post-action listener: _resource_record_post_action 14:18:28 INFO - [mozharness: 2017-10-03 21:18:28.536167Z] Finished clobber step (success) 14:18:28 INFO - [mozharness: 2017-10-03 21:18:28.536263Z] Running read-buildbot-config step. 14:18:28 INFO - Running pre-action listener: _resource_record_pre_action 14:18:28 INFO - Running main action method: read_buildbot_config 14:18:28 INFO - Using buildbot properties: 14:18:28 INFO - { 14:18:28 INFO - "project": "", 14:18:28 INFO - "product": "firefox", 14:18:28 INFO - "who": "kwierso@gmail.com", 14:18:28 INFO - "installer_path": "public/build/target.tar.bz2", 14:18:28 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 14:18:28 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 14:18:28 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 14:18:28 INFO - "stage_platform": "linux64", 14:18:28 INFO - "basedir": "/builds/slave/test", 14:18:28 INFO - "buildnumber": 892, 14:18:28 INFO - "platform": "ubuntu64_hw", 14:18:28 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:18:28 INFO - "slavebuilddir": "test", 14:18:28 INFO - "taskId": "ACk-8_FpTVS_1m2HmL5rYw", 14:18:28 INFO - "branch": "mozilla-central", 14:18:28 INFO - "script_repo_revision": "production", 14:18:28 INFO - "revision": "65a5054a1f922b83929c80658062f441ca3da6a0", 14:18:28 INFO - "slavename": "talos-linux64-ix-048", 14:18:28 INFO - "repo_path": "mozilla-central" 14:18:28 INFO - } 14:18:28 INFO - Finding installer, test and symbols from parent task. 14:18:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ACk-8_FpTVS_1m2HmL5rYw'}, attempt #1 14:18:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ACk-8_FpTVS_1m2HmL5rYw'}, attempt #1 14:18:29 INFO - Task dependencies: V7ZwxuDyRX-_fu7gAE5ohQ 14:18:29 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ'}, attempt #1 14:18:30 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:30 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json 14:18:30 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip 14:18:30 INFO - Running post-action listener: _resource_record_post_action 14:18:30 INFO - [mozharness: 2017-10-03 21:18:30.288604Z] Finished read-buildbot-config step (success) 14:18:30 INFO - [mozharness: 2017-10-03 21:18:30.288820Z] Running download-and-extract step. 14:18:30 INFO - Running pre-action listener: _resource_record_pre_action 14:18:30 INFO - Running main action method: download_and_extract 14:18:30 INFO - mkdir: /builds/slave/test/build/tests 14:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:30 INFO - trying https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json 14:18:30 INFO - Downloading https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 14:18:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 14:18:30 INFO - Downloaded 989 bytes. 14:18:30 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 14:18:30 INFO - Using the following test package requirements: 14:18:30 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 14:18:30 INFO - u'common': [u'target.common.tests.zip'], 14:18:30 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 14:18:30 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 14:18:30 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 14:18:30 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 14:18:30 INFO - u'mozbase': [u'target.common.tests.zip'], 14:18:30 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 14:18:30 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 14:18:30 INFO - u'web-platform': [u'target.common.tests.zip', 14:18:30 INFO - u'target.web-platform.tests.tar.gz'], 14:18:30 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 14:18:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 14:18:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.common.tests.zip 14:18:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.common.tests.zip'}, attempt #1 14:18:30 INFO - Fetch https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.common.tests.zip into memory 14:18:34 INFO - Content-Length response header: 55304183 14:18:34 INFO - Bytes received: 55304183 14:18:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.talos.tests.zip 14:18:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.talos.tests.zip'}, attempt #1 14:18:44 INFO - Fetch https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.talos.tests.zip into memory 14:18:46 INFO - Content-Length response header: 14533844 14:18:46 INFO - Bytes received: 14533844 14:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:47 INFO - trying https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:47 INFO - Downloading https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 14:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 14:18:50 INFO - Downloaded 61572871 bytes. 14:18:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:50 INFO - mkdir: /builds/slave/test/properties 14:18:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:50 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:50 INFO - Contents: 14:18:50 INFO - build_url:https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 14:18:50 INFO - Running post-action listener: _resource_record_post_action 14:18:50 INFO - Running post-action listener: find_tests_for_verification 14:18:50 INFO - Running post-action listener: set_extra_try_arguments 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.529231Z] Finished download-and-extract step (success) 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.529471Z] Running populate-webroot step. 14:18:50 INFO - Running pre-action listener: _resource_record_pre_action 14:18:50 INFO - Running main action method: populate_webroot 14:18:50 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:50 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tresize', 14:18:50 INFO - 'tcanvasmark']}, 14:18:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:50 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['dromaeo_css', 14:18:50 INFO - 'kraken']}, 14:18:50 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:50 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:50 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'tests': ['damp', 'tps']}, 14:18:50 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['damp', 'tps']}, 14:18:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:50 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['dromaeo_dom']}, 14:18:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:18:50 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['basic_compositor_video', 14:18:50 INFO - 'glvideo']}, 14:18:50 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:18:50 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['ts_paint_webext', 14:18:50 INFO - 'tp5o_webext']}, 14:18:50 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_many_windows', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint', 14:18:50 INFO - 'cpstartup']}, 14:18:50 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['a11yr', 14:18:50 INFO - 'ts_paint', 14:18:50 INFO - 'tpaint', 14:18:50 INFO - 'sessionrestore', 14:18:50 INFO - 'sessionrestore_many_windows', 14:18:50 INFO - 'sessionrestore_no_auto_restore', 14:18:50 INFO - 'tabpaint', 14:18:50 INFO - 'cpstartup']}, 14:18:50 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 14:18:50 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 14:18:50 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['perf_reftest_singletons']}, 14:18:50 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['perf_reftest']}, 14:18:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:50 INFO - 'tsvgr_opacity', 14:18:50 INFO - 'tart', 14:18:50 INFO - 'tscrollx', 14:18:50 INFO - 'tsvg_static']}, 14:18:50 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tsvgx', 14:18:50 INFO - 'tsvgr_opacity', 14:18:50 INFO - 'tart', 14:18:50 INFO - 'tscrollx', 14:18:50 INFO - 'tsvg_static']}, 14:18:50 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:18:50 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo'], 14:18:50 INFO - 'tests': ['tp5o']}, 14:18:50 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:18:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:18:50 INFO - 'talos_options': ['--mitmproxy', 14:18:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:18:50 INFO - '--firstNonBlankPaint'], 14:18:50 INFO - 'tests': ['tp6_google', 14:18:50 INFO - 'tp6_youtube', 14:18:50 INFO - 'tp6_amazon', 14:18:50 INFO - 'tp6_facebook']}, 14:18:50 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:18:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:18:50 INFO - 'talos_options': ['--disable-stylo', 14:18:50 INFO - '--mitmproxy', 14:18:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:18:50 INFO - '--firstNonBlankPaint'], 14:18:50 INFO - 'tests': ['tp6_google', 14:18:50 INFO - 'tp6_youtube', 14:18:50 INFO - 'tp6_amazon', 14:18:50 INFO - 'tp6_facebook']}, 14:18:50 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:18:50 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 14:18:50 INFO - 'talos_options': ['--stylo-threads=1', 14:18:50 INFO - '--mitmproxy', 14:18:50 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:18:50 INFO - '--firstNonBlankPaint'], 14:18:50 INFO - 'tests': ['tp6_google', 14:18:50 INFO - 'tp6_youtube', 14:18:50 INFO - 'tp6_amazon', 14:18:50 INFO - 'tp6_facebook']}, 14:18:50 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--xperf_path', 14:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:50 INFO - 'tests': ['tp5n']}, 14:18:50 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 14:18:50 INFO - 'talos_options': ['--disable-stylo', 14:18:50 INFO - '--xperf_path', 14:18:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:50 INFO - 'tests': ['tp5n']}}} 14:18:50 INFO - Running post-action listener: _resource_record_post_action 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.546233Z] Finished populate-webroot step (success) 14:18:50 INFO - [mozharness: 2017-10-03 21:18:50.546332Z] Running create-virtualenv step. 14:18:50 INFO - Running pre-action listener: _resource_record_pre_action 14:18:50 INFO - Running main action method: create_virtualenv 14:18:50 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:50 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:18:50 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 14:18:50 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:18:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:51 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:51 INFO - Installing pip...............done. 14:18:51 INFO - Return code: 0 14:18:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:18:51 INFO - Reading from file tmpfile_stdout 14:18:51 INFO - Output received: 14:18:51 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 14:18:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:18:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:18:51 INFO - Using env: {'DISPLAY': ':0', 14:18:51 INFO - 'HOME': '/home/cltbld', 14:18:51 INFO - 'LANG': 'en_US.UTF-8', 14:18:51 INFO - 'LANGUAGE': 'en_US:en', 14:18:51 INFO - 'LOGNAME': 'cltbld', 14:18:51 INFO - 'MAIL': '/var/mail/cltbld', 14:18:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:51 INFO - 'MOZ_NO_REMOTE': '1', 14:18:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:51 INFO - 'NO_EM_RESTART': '1', 14:18:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:51 INFO - 'PWD': '/builds/slave/test', 14:18:51 INFO - 'SHELL': '/bin/bash', 14:18:51 INFO - 'SHLVL': '1', 14:18:51 INFO - 'TERM': 'linux', 14:18:51 INFO - 'TMOUT': '86400', 14:18:51 INFO - 'USER': 'cltbld', 14:18:51 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:18:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:51 INFO - '_': '/tools/buildbot/bin/python'} 14:18:53 INFO - Ignoring indexes: http://pypi.python.org/simple/ 14:18:53 INFO - Downloading/unpacking pip>=1.5 14:18:53 INFO - Running setup.py egg_info for package pip 14:18:53 INFO - warning: no files found matching 'pip/cacert.pem' 14:18:53 INFO - warning: no files found matching '*.html' under directory 'docs' 14:18:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 14:18:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 14:18:53 INFO - Installing collected packages: pip 14:18:53 INFO - Found existing installation: pip 1.1 14:18:53 INFO - Uninstalling pip: 14:18:53 INFO - Successfully uninstalled pip 14:18:53 INFO - Running setup.py install for pip 14:18:53 INFO - warning: no files found matching 'pip/cacert.pem' 14:18:53 INFO - warning: no files found matching '*.html' under directory 'docs' 14:18:53 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 14:18:53 INFO - no previously-included directories found matching 'docs/_build/_sources' 14:18:53 INFO - Installing pip script to /builds/slave/test/build/venv/bin 14:18:53 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 14:18:53 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 14:18:53 INFO - Successfully installed pip 14:18:53 INFO - Cleaning up... 14:18:53 INFO - Return code: 0 14:18:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:18:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:18:53 INFO - Using env: {'DISPLAY': ':0', 14:18:53 INFO - 'HOME': '/home/cltbld', 14:18:53 INFO - 'LANG': 'en_US.UTF-8', 14:18:53 INFO - 'LANGUAGE': 'en_US:en', 14:18:53 INFO - 'LOGNAME': 'cltbld', 14:18:53 INFO - 'MAIL': '/var/mail/cltbld', 14:18:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:53 INFO - 'MOZ_NO_REMOTE': '1', 14:18:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:53 INFO - 'NO_EM_RESTART': '1', 14:18:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:53 INFO - 'PWD': '/builds/slave/test', 14:18:53 INFO - 'SHELL': '/bin/bash', 14:18:53 INFO - 'SHLVL': '1', 14:18:53 INFO - 'TERM': 'linux', 14:18:53 INFO - 'TMOUT': '86400', 14:18:53 INFO - 'USER': 'cltbld', 14:18:53 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:18:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:53 INFO - '_': '/tools/buildbot/bin/python'} 14:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:53 INFO - Downloading/unpacking psutil>=3.1.1 14:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:55 INFO - Installing collected packages: psutil 14:18:55 INFO - Running setup.py install for psutil 14:18:55 INFO - building 'psutil._psutil_linux' extension 14:18:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:18:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:18:55 INFO - building 'psutil._psutil_posix' extension 14:18:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:18:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:18:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:55 INFO - Successfully installed psutil 14:18:55 INFO - Cleaning up... 14:18:56 INFO - Return code: 0 14:18:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:18:56 INFO - Using env: {'DISPLAY': ':0', 14:18:56 INFO - 'HOME': '/home/cltbld', 14:18:56 INFO - 'LANG': 'en_US.UTF-8', 14:18:56 INFO - 'LANGUAGE': 'en_US:en', 14:18:56 INFO - 'LOGNAME': 'cltbld', 14:18:56 INFO - 'MAIL': '/var/mail/cltbld', 14:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:56 INFO - 'MOZ_NO_REMOTE': '1', 14:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:56 INFO - 'NO_EM_RESTART': '1', 14:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:56 INFO - 'PWD': '/builds/slave/test', 14:18:56 INFO - 'SHELL': '/bin/bash', 14:18:56 INFO - 'SHLVL': '1', 14:18:56 INFO - 'TERM': 'linux', 14:18:56 INFO - 'TMOUT': '86400', 14:18:56 INFO - 'USER': 'cltbld', 14:18:56 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:56 INFO - '_': '/tools/buildbot/bin/python'} 14:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:18:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:18:58 INFO - Installing collected packages: mozsystemmonitor 14:18:58 INFO - Running setup.py install for mozsystemmonitor 14:18:58 INFO - Successfully installed mozsystemmonitor 14:18:58 INFO - Cleaning up... 14:18:58 INFO - Return code: 0 14:18:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:18:58 INFO - Using env: {'DISPLAY': ':0', 14:18:58 INFO - 'HOME': '/home/cltbld', 14:18:58 INFO - 'LANG': 'en_US.UTF-8', 14:18:58 INFO - 'LANGUAGE': 'en_US:en', 14:18:58 INFO - 'LOGNAME': 'cltbld', 14:18:58 INFO - 'MAIL': '/var/mail/cltbld', 14:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:58 INFO - 'MOZ_NO_REMOTE': '1', 14:18:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:58 INFO - 'NO_EM_RESTART': '1', 14:18:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:58 INFO - 'PWD': '/builds/slave/test', 14:18:58 INFO - 'SHELL': '/bin/bash', 14:18:58 INFO - 'SHLVL': '1', 14:18:58 INFO - 'TERM': 'linux', 14:18:58 INFO - 'TMOUT': '86400', 14:18:58 INFO - 'USER': 'cltbld', 14:18:58 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:58 INFO - '_': '/tools/buildbot/bin/python'} 14:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:58 INFO - Downloading/unpacking jsonschema==2.5.1 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:59 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:59 INFO - warning: no files found matching '*.txt' 14:18:59 INFO - no previously-included directories found matching 'build' 14:18:59 INFO - no previously-included directories found matching 'dist' 14:18:59 INFO - no previously-included directories found matching '.git*' 14:18:59 INFO - Installing collected packages: jsonschema, functools32 14:18:59 INFO - Running setup.py install for functools32 14:18:59 INFO - warning: no files found matching '*.txt' 14:18:59 INFO - no previously-included directories found matching 'build' 14:18:59 INFO - no previously-included directories found matching 'dist' 14:18:59 INFO - no previously-included directories found matching '.git*' 14:18:59 INFO - Successfully installed jsonschema functools32 14:18:59 INFO - Cleaning up... 14:18:59 INFO - Return code: 0 14:18:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:18:59 INFO - Using env: {'DISPLAY': ':0', 14:18:59 INFO - 'HOME': '/home/cltbld', 14:18:59 INFO - 'LANG': 'en_US.UTF-8', 14:18:59 INFO - 'LANGUAGE': 'en_US:en', 14:18:59 INFO - 'LOGNAME': 'cltbld', 14:18:59 INFO - 'MAIL': '/var/mail/cltbld', 14:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:59 INFO - 'MOZ_NO_REMOTE': '1', 14:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:59 INFO - 'NO_EM_RESTART': '1', 14:18:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:59 INFO - 'PWD': '/builds/slave/test', 14:18:59 INFO - 'SHELL': '/bin/bash', 14:18:59 INFO - 'SHLVL': '1', 14:18:59 INFO - 'TERM': 'linux', 14:18:59 INFO - 'TMOUT': '86400', 14:18:59 INFO - 'USER': 'cltbld', 14:18:59 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:59 INFO - '_': '/tools/buildbot/bin/python'} 14:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:19:00 INFO - Cleaning up... 14:19:00 INFO - Return code: 0 14:19:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:19:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:19:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:19:00 INFO - Using env: {'DISPLAY': ':0', 14:19:00 INFO - 'HOME': '/home/cltbld', 14:19:00 INFO - 'LANG': 'en_US.UTF-8', 14:19:00 INFO - 'LANGUAGE': 'en_US:en', 14:19:00 INFO - 'LOGNAME': 'cltbld', 14:19:00 INFO - 'MAIL': '/var/mail/cltbld', 14:19:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:00 INFO - 'MOZ_NO_REMOTE': '1', 14:19:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:00 INFO - 'NO_EM_RESTART': '1', 14:19:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:00 INFO - 'PWD': '/builds/slave/test', 14:19:00 INFO - 'SHELL': '/bin/bash', 14:19:00 INFO - 'SHLVL': '1', 14:19:00 INFO - 'TERM': 'linux', 14:19:00 INFO - 'TMOUT': '86400', 14:19:00 INFO - 'USER': 'cltbld', 14:19:00 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:00 INFO - '_': '/tools/buildbot/bin/python'} 14:19:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:00 INFO - Downloading/unpacking blobuploader==1.2.4 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:01 INFO - Downloading docopt-0.6.1.tar.gz 14:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:02 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:02 INFO - Running setup.py install for blobuploader 14:19:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:02 INFO - Running setup.py install for requests 14:19:02 INFO - Running setup.py install for docopt 14:19:02 INFO - Successfully installed blobuploader requests docopt 14:19:02 INFO - Cleaning up... 14:19:02 INFO - Return code: 0 14:19:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:19:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:02 INFO - Using env: {'DISPLAY': ':0', 14:19:02 INFO - 'HOME': '/home/cltbld', 14:19:02 INFO - 'LANG': 'en_US.UTF-8', 14:19:02 INFO - 'LANGUAGE': 'en_US:en', 14:19:02 INFO - 'LOGNAME': 'cltbld', 14:19:02 INFO - 'MAIL': '/var/mail/cltbld', 14:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:02 INFO - 'MOZ_NO_REMOTE': '1', 14:19:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:02 INFO - 'NO_EM_RESTART': '1', 14:19:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:02 INFO - 'PWD': '/builds/slave/test', 14:19:02 INFO - 'SHELL': '/bin/bash', 14:19:02 INFO - 'SHLVL': '1', 14:19:02 INFO - 'TERM': 'linux', 14:19:02 INFO - 'TMOUT': '86400', 14:19:02 INFO - 'USER': 'cltbld', 14:19:02 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:02 INFO - '_': '/tools/buildbot/bin/python'} 14:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:02 INFO - Running setup.py (path:/tmp/pip-85N5zM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:02 INFO - Running setup.py (path:/tmp/pip-wvSlit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:02 INFO - Running setup.py (path:/tmp/pip-OEXakQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:03 INFO - Running setup.py (path:/tmp/pip-z8aACs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:03 INFO - Running setup.py (path:/tmp/pip-92zLEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:03 INFO - Running setup.py (path:/tmp/pip-g8Xkd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:03 INFO - Running setup.py (path:/tmp/pip-IzC0Ei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:03 INFO - Running setup.py (path:/tmp/pip-wU8gPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:03 INFO - Running setup.py (path:/tmp/pip-tdOtlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:03 INFO - Running setup.py (path:/tmp/pip-IJ1TYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:03 INFO - Running setup.py (path:/tmp/pip-JA6EUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:03 INFO - Running setup.py (path:/tmp/pip-79dP6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:03 INFO - Running setup.py (path:/tmp/pip-MmNVrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:03 INFO - Running setup.py (path:/tmp/pip-tRZV8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:03 INFO - Running setup.py (path:/tmp/pip-D6juzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:03 INFO - Running setup.py (path:/tmp/pip-tRFulW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:03 INFO - Running setup.py (path:/tmp/pip-8dOh_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:04 INFO - Running setup.py install for manifestparser 14:19:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Running setup.py install for mozcrash 14:19:04 INFO - Running setup.py install for mozdebug 14:19:04 INFO - Running setup.py install for mozdevice 14:19:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Running setup.py install for mozfile 14:19:04 INFO - Running setup.py install for mozhttpd 14:19:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Running setup.py install for mozinfo 14:19:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Running setup.py install for mozInstall 14:19:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:04 INFO - Running setup.py install for mozleak 14:19:04 INFO - Running setup.py install for mozlog 14:19:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Running setup.py install for moznetwork 14:19:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Running setup.py install for mozprocess 14:19:05 INFO - Running setup.py install for mozprofile 14:19:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Running setup.py install for mozrunner 14:19:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Running setup.py install for mozscreenshot 14:19:05 INFO - Running setup.py install for moztest 14:19:05 INFO - Running setup.py install for mozversion 14:19:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:19:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:05 INFO - Cleaning up... 14:19:05 INFO - Return code: 0 14:19:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:05 INFO - Using env: {'DISPLAY': ':0', 14:19:05 INFO - 'HOME': '/home/cltbld', 14:19:05 INFO - 'LANG': 'en_US.UTF-8', 14:19:05 INFO - 'LANGUAGE': 'en_US:en', 14:19:05 INFO - 'LOGNAME': 'cltbld', 14:19:05 INFO - 'MAIL': '/var/mail/cltbld', 14:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:05 INFO - 'MOZ_NO_REMOTE': '1', 14:19:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:05 INFO - 'NO_EM_RESTART': '1', 14:19:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:05 INFO - 'PWD': '/builds/slave/test', 14:19:05 INFO - 'SHELL': '/bin/bash', 14:19:05 INFO - 'SHLVL': '1', 14:19:05 INFO - 'TERM': 'linux', 14:19:05 INFO - 'TMOUT': '86400', 14:19:05 INFO - 'USER': 'cltbld', 14:19:05 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:05 INFO - '_': '/tools/buildbot/bin/python'} 14:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:06 INFO - Running setup.py (path:/tmp/pip-OCxxyB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:06 INFO - Running setup.py (path:/tmp/pip-cz3MPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:06 INFO - Running setup.py (path:/tmp/pip-6RhgMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:06 INFO - Running setup.py (path:/tmp/pip-Bpj5Od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:06 INFO - Running setup.py (path:/tmp/pip-_0mkzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:06 INFO - Running setup.py (path:/tmp/pip-zJKMnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:06 INFO - Running setup.py (path:/tmp/pip-tluw0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:06 INFO - Running setup.py (path:/tmp/pip-tjJwSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:06 INFO - Running setup.py (path:/tmp/pip-lzXid9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:06 INFO - Running setup.py (path:/tmp/pip-khTstO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:06 INFO - Running setup.py (path:/tmp/pip-Yn1qaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:06 INFO - Running setup.py (path:/tmp/pip-5r7O1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:06 INFO - Running setup.py (path:/tmp/pip-_JUmud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:19:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:06 INFO - Running setup.py (path:/tmp/pip-powuhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:07 INFO - Running setup.py (path:/tmp/pip-20deoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:07 INFO - Running setup.py (path:/tmp/pip-S8pjEf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:19:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:07 INFO - Running setup.py (path:/tmp/pip-PCq7ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:07 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:19:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:08 INFO - Downloading blessings-1.6.tar.gz 14:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:08 INFO - Installing collected packages: blessings 14:19:08 INFO - Running setup.py install for blessings 14:19:08 INFO - Successfully installed blessings 14:19:08 INFO - Cleaning up... 14:19:08 INFO - Return code: 0 14:19:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:19:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:08 INFO - Reading from file tmpfile_stdout 14:19:08 INFO - Current package versions: 14:19:08 INFO - argparse == 1.2.1 14:19:08 INFO - blessings == 1.6 14:19:08 INFO - blobuploader == 1.2.4 14:19:08 INFO - docopt == 0.6.1 14:19:08 INFO - functools32 == 3.2.3-2 14:19:08 INFO - jsonschema == 2.5.1 14:19:08 INFO - manifestparser == 1.1 14:19:08 INFO - mozInstall == 1.13 14:19:08 INFO - mozcrash == 1.0 14:19:08 INFO - mozdebug == 0.1 14:19:08 INFO - mozdevice == 0.51 14:19:08 INFO - mozfile == 1.2 14:19:08 INFO - mozhttpd == 0.7 14:19:08 INFO - mozinfo == 0.10 14:19:08 INFO - mozleak == 0.1 14:19:08 INFO - mozlog == 3.5 14:19:08 INFO - moznetwork == 0.27 14:19:08 INFO - mozprocess == 0.25 14:19:08 INFO - mozprofile == 0.28 14:19:08 INFO - mozrunner == 6.13 14:19:08 INFO - mozscreenshot == 0.1 14:19:08 INFO - mozsystemmonitor == 0.3 14:19:08 INFO - moztest == 0.8 14:19:08 INFO - mozversion == 1.4 14:19:08 INFO - psutil == 3.1.1 14:19:08 INFO - requests == 1.2.3 14:19:08 INFO - wsgiref == 0.1.2 14:19:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:19:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:19:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:08 INFO - Using env: {'DISPLAY': ':0', 14:19:08 INFO - 'HOME': '/home/cltbld', 14:19:08 INFO - 'LANG': 'en_US.UTF-8', 14:19:08 INFO - 'LANGUAGE': 'en_US:en', 14:19:08 INFO - 'LOGNAME': 'cltbld', 14:19:08 INFO - 'MAIL': '/var/mail/cltbld', 14:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:08 INFO - 'MOZ_NO_REMOTE': '1', 14:19:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:08 INFO - 'NO_EM_RESTART': '1', 14:19:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:08 INFO - 'PWD': '/builds/slave/test', 14:19:08 INFO - 'SHELL': '/bin/bash', 14:19:08 INFO - 'SHLVL': '1', 14:19:08 INFO - 'TERM': 'linux', 14:19:08 INFO - 'TMOUT': '86400', 14:19:08 INFO - 'USER': 'cltbld', 14:19:08 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:08 INFO - '_': '/tools/buildbot/bin/python'} 14:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:19:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:19:09 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 14:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:19:10 INFO - Installing collected packages: simplejson 14:19:10 INFO - Running setup.py install for simplejson 14:19:10 INFO - building 'simplejson._speedups' extension 14:19:10 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 14:19:11 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 14:19:11 INFO - Successfully installed simplejson 14:19:11 INFO - Cleaning up... 14:19:11 INFO - Return code: 0 14:19:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1c271f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1defb70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f949c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:19:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:19:11 INFO - Using env: {'DISPLAY': ':0', 14:19:11 INFO - 'HOME': '/home/cltbld', 14:19:11 INFO - 'LANG': 'en_US.UTF-8', 14:19:11 INFO - 'LANGUAGE': 'en_US:en', 14:19:11 INFO - 'LOGNAME': 'cltbld', 14:19:11 INFO - 'MAIL': '/var/mail/cltbld', 14:19:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:11 INFO - 'MOZ_NO_REMOTE': '1', 14:19:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:11 INFO - 'NO_EM_RESTART': '1', 14:19:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:11 INFO - 'PWD': '/builds/slave/test', 14:19:11 INFO - 'SHELL': '/bin/bash', 14:19:11 INFO - 'SHLVL': '1', 14:19:11 INFO - 'TERM': 'linux', 14:19:11 INFO - 'TMOUT': '86400', 14:19:11 INFO - 'USER': 'cltbld', 14:19:11 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:11 INFO - '_': '/tools/buildbot/bin/python'} 14:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:19:11 INFO - Cleaning up... 14:19:11 INFO - Return code: 0 14:19:11 INFO - Running post-action listener: _resource_record_post_action 14:19:11 INFO - Running post-action listener: _start_resource_monitoring 14:19:11 INFO - Starting resource monitoring. 14:19:11 INFO - [mozharness: 2017-10-03 21:19:11.672946Z] Finished create-virtualenv step (success) 14:19:11 INFO - [mozharness: 2017-10-03 21:19:11.673536Z] Running install step. 14:19:11 INFO - Running pre-action listener: _resource_record_pre_action 14:19:11 INFO - Running main action method: install 14:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:11 INFO - Reading from file tmpfile_stdout 14:19:11 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:19:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:19:12 INFO - Reading from file tmpfile_stdout 14:19:12 INFO - Output received: 14:19:12 INFO - Usage: mozinstall [options] installer 14:19:12 INFO - Options: 14:19:12 INFO - -h, --help show this help message and exit 14:19:12 INFO - -d DEST, --destination=DEST 14:19:12 INFO - Directory to install application into. [default: 14:19:12 INFO - "/builds/slave/test"] 14:19:12 INFO - --app=APP Application being installed. [default: firefox] 14:19:12 INFO - mkdir: /builds/slave/test/build/application 14:19:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:19:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 14:19:29 INFO - Reading from file tmpfile_stdout 14:19:29 INFO - Output received: 14:19:29 INFO - /builds/slave/test/build/application/firefox/firefox 14:19:29 INFO - Running post-action listener: _resource_record_post_action 14:19:29 INFO - [mozharness: 2017-10-03 21:19:29.886390Z] Finished install step (success) 14:19:29 INFO - [mozharness: 2017-10-03 21:19:29.886641Z] Running setup-mitmproxy step. 14:19:29 INFO - Running pre-action listener: _resource_record_pre_action 14:19:29 INFO - Running main action method: setup_mitmproxy 14:19:29 INFO - Skipping: mitmproxy is not required 14:19:29 INFO - Running post-action listener: _resource_record_post_action 14:19:29 INFO - [mozharness: 2017-10-03 21:19:29.887770Z] Finished setup-mitmproxy step (success) 14:19:29 INFO - [mozharness: 2017-10-03 21:19:29.888005Z] Running run-tests step. 14:19:29 INFO - Running pre-action listener: _resource_record_pre_action 14:19:29 INFO - Running pre-action listener: _set_gcov_prefix 14:19:29 INFO - Running main action method: run_tests 14:19:29 WARNING - Try message not found. 14:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:29 INFO - Python 2.7.3 14:19:29 INFO - Return code: 0 14:19:29 INFO - grabbing minidump binary from tooltool 14:19:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f91d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f92a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f92ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:19:29 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:19:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:19:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:30 INFO - Return code: 0 14:19:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:19:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:30 INFO - ENV: RUST_BACKTRACE is now full 14:19:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:19:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 14:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 14:19:30 INFO - Using env: {'DISPLAY': ':0', 14:19:30 INFO - 'HOME': '/home/cltbld', 14:19:30 INFO - 'LANG': 'en_US.UTF-8', 14:19:30 INFO - 'LANGUAGE': 'en_US:en', 14:19:30 INFO - 'LOGNAME': 'cltbld', 14:19:30 INFO - 'MAIL': '/var/mail/cltbld', 14:19:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:30 INFO - 'MOZ_NO_REMOTE': '1', 14:19:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:30 INFO - 'NO_EM_RESTART': '1', 14:19:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:30 INFO - 'PWD': '/builds/slave/test', 14:19:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:30 INFO - 'RUST_BACKTRACE': 'full', 14:19:30 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:19:30 INFO - 'SHELL': '/bin/bash', 14:19:30 INFO - 'SHLVL': '1', 14:19:30 INFO - 'TERM': 'linux', 14:19:30 INFO - 'TMOUT': '86400', 14:19:30 INFO - 'USER': 'cltbld', 14:19:30 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:30 INFO - '_': '/tools/buildbot/bin/python'} 14:19:30 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 14:19:30 INFO - mozversion application_buildid: 20171003202555 14:19:30 INFO - mozversion application_changeset: 65a5054a1f922b83929c80658062f441ca3da6a0 14:19:30 INFO - mozversion application_display_name: Nightly 14:19:30 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:30 INFO - mozversion application_name: Firefox 14:19:30 INFO - mozversion application_remotingname: firefox 14:19:30 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:19:30 INFO - mozversion application_vendor: Mozilla 14:19:30 INFO - mozversion application_version: 58.0a1 14:19:30 INFO - mozversion platform_buildid: 20171003202555 14:19:30 INFO - mozversion platform_changeset: 65a5054a1f922b83929c80658062f441ca3da6a0 14:19:30 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:19:30 INFO - mozversion platform_version: 58.0a1 14:19:30 INFO - using testdate: 1507065570 14:19:30 INFO - actual date: 1507065570 14:19:30 INFO - starting webserver on 'localhost:52223' 14:19:30 INFO - SUITE-START | Running 2 tests 14:19:30 INFO - TEST-START | dromaeo_css 14:19:30 INFO - Initialising browser for dromaeo_css test... 14:19:30 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52223/getInfo.html -profile /tmp/tmpDYv_z3/profile 14:19:30 INFO - TEST-INFO | started process 4863 (/builds/slave/test/build/application/firefox/firefox http://localhost:52223/getInfo.html) 14:19:36 INFO - TEST-INFO | 4863: exit 0 14:19:36 INFO - Browser initialized. 14:19:36 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:36 INFO - Using env: {'DISPLAY': ':0', 14:19:36 INFO - 'HOME': '/home/cltbld', 14:19:36 INFO - 'JSGC_DISABLE_POISONING': '1', 14:19:36 INFO - 'LANG': 'en_US.UTF-8', 14:19:36 INFO - 'LANGUAGE': 'en_US:en', 14:19:36 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 14:19:36 INFO - 'LOGNAME': 'cltbld', 14:19:36 INFO - 'MAIL': '/var/mail/cltbld', 14:19:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:19:36 INFO - 'MOZ_CRASHREPORTER': '1', 14:19:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:36 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 14:19:36 INFO - 'MOZ_NO_REMOTE': '1', 14:19:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:19:36 INFO - 'NO_EM_RESTART': '1', 14:19:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:19:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:36 INFO - 'PWD': '/builds/slave/test', 14:19:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:36 INFO - 'RUST_BACKTRACE': 'full', 14:19:36 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:19:36 INFO - 'SHELL': '/bin/bash', 14:19:36 INFO - 'SHLVL': '1', 14:19:36 INFO - 'TERM': 'linux', 14:19:36 INFO - 'TMOUT': '86400', 14:19:36 INFO - 'USER': 'cltbld', 14:19:36 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:19:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:36 INFO - '_': '/tools/buildbot/bin/python'} 14:19:36 INFO - TEST-INFO | started process 5134 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDYv_z3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:37 INFO - PID 5134 | 14:19:37 INFO - PID 5134 | (/builds/slave/test/build/application/firefox/firefox:5183): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:19:37 INFO - PID 5134 | 14:19:47 INFO - PID 5134 | RSS: Main: 152621056 14:19:47 INFO - PID 5134 | 14:22:44 INFO - PID 5134 | Cycle 1(1): loaded http://localhost:52223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52223/tests/dromaeo/cssquery-ext.html) 14:22:45 INFO - PID 5134 | RSS: Main: 150073344 14:22:45 INFO - PID 5134 | 14:25:47 INFO - PID 5134 | Cycle 1(1): loaded http://localhost:52223/tests/dromaeo/cssquery-ext.html (next: http://localhost:52223/tests/dromaeo/cssquery-jquery.html) 14:25:48 INFO - PID 5134 | RSS: Main: 151359488 14:25:48 INFO - PID 5134 | 14:28:50 INFO - PID 5134 | Cycle 1(1): loaded http://localhost:52223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52223/tests/dromaeo/cssquery-mootools.html) 14:28:50 INFO - PID 5134 | RSS: Main: 151887872 14:28:50 INFO - PID 5134 | 14:31:52 INFO - PID 5134 | Cycle 1(1): loaded http://localhost:52223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52223/tests/dromaeo/cssquery-prototype.html) 14:31:52 INFO - PID 5134 | RSS: Main: 151920640 14:31:52 INFO - PID 5134 | 14:34:56 INFO - PID 5134 | Cycle 1(1): loaded http://localhost:52223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52223/tests/dromaeo/cssquery-yui.html) 14:34:56 INFO - PID 5134 | RSS: Main: 154832896 14:34:56 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | Cycle 1(1): loaded http://localhost:52223/tests/dromaeo/cssquery-yui.html (next: http://localhost:52223/tests/dromaeo/cssquery-dojo.html) 14:37:58 INFO - PID 5134 | RSS: Main: 156078080 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | __start_tp_report 14:37:58 INFO - PID 5134 | _x_x_mozilla_page_load 14:37:58 INFO - PID 5134 | _x_x_mozilla_page_load_details 14:37:58 INFO - PID 5134 | |i|pagename|runs| 14:37:58 INFO - PID 5134 | |0;dojo.html;3153.9369212615734;3166.9524957125645;3187.8565464554094;3189.2824114574237;3215.228345197152;6188.226471691039;6329.841753956153;6339.746410143594;6400.967995160024;6464.967675161623;6325.493960483162;6339.809805705825;6457.6448295343735;6467.935320646792;6477.74089036438;917.5778225181973;5580.414056524068;5923.05231163014;5951.077582974646;6002.009668404708;5769;5829.708514574276;5855.941440585603;5864.941350586503;5945.137954996523;5918.852028699295;5943.3165186003735;5958.165856780054;5969.403059694039;5982.8504287392725;5875.294964604275;5886.411358864122;5951.880962380771;5965.612235204741;5979.641221526679;5705.4294570543025;5762.4525670061275;5764.625299355528;5866.354700982888;5920.792772252994;6272.749090036393;6285.402886725754;6368.745250189987;6412.967935160341;6419.967900160469;6785.355391237825;6886.208086070116;6889.965550172267;6892.689828957709;6899.689513971884;6784.694688739019;6882.69027893746;6959.8956015659805;6969.407600353976;6970.477214208955;6205.968970155165;6350.396712312323;6356.300806911236;6362.745490180388;6366.172397588331;7004.15950085993;7071.92928070723;7166.8208294792;7167.426605871622;7173.426125909915;6702.664866756642;6777.085093512313;6800.65996700163;6802.795916122524;6803.65981700913;6808.2510923798345;6809.216940051859;6824;6899.689513971783;6904.447644188453;6631.23740769808;6705.765298214539;6796.694148763318;6879.0025446311;6899.689513971783;6858.348456896587;6867.450603951672;6980.371766541036;6982.965085174542;6990.790276291719;5005.799768009311;5075.289459475676;5089;5091.821786237538;5101.69389836611;4346.891327716832;4401.427814384109;4460.375547423363;4464.2633965396;4472.843450479218;5821.388754180834;5845.240118784531;5858.443447872445;5861.32594751609;5864.03243464832;6918.584884906922;6926.376626103574;6928.16861976566;6938.826529336706;6942.583444993316;5845.1232315153065;5845.357010728816;5930.8813823723285;5934.1692163097205;5938.049912014057;5665.093585026376;5804.100364443518;5843.766249350064;5908.99547076998;5914.733836977346;6458.580192287486;6496.740130394731;6543.312952140052;6590.275069742324;6632.237292711304;6847.041414202115;6882.8623427530165;6941.028256044158;6976.093107896144;6978.30216978288;6556.2788093310655;6598.505112116514;6601.537892347682;6693.09643198162;6703.597784132968;6719.227288861844;6792;6799.626020568916;6805.217399999064;6812.284710105368;6918.135233095863;6973.058637083929;6973.47698922593;6976.441884649317;6981.685824137824;6527.902081468686;6584.8024559263295;6640.9335906642245;6691.498137639599;6717.462602991846;6485.805425837232;6609.405153536205;6650.534462587766;6654.600723956578;6728.7308507659145;6591.143151390289;6625.900611490735;6626.237982632059;6630.53586248958;6682.599044057372;6584.76953306632;6706.295838936841;6754.425873800782;6787.21946976104;6795.38841504267;6304.810855674337;6387.489000880011;6387.840303992437;6430.260520040255;6575.934240657723 14:37:58 INFO - PID 5134 | |1;ext.html;868.9087645797191;878.3324673248329;880.8766772651834;894.3069121430892;902.7021083042592;40394.19211615766;40924.795376023154;40928.795356023176;40932;41162.58837411625;37165;37336.62663373366;37418.25163496729;37432.625673743256;37653;7073;7192.172900116475;7194.568325900436;7202.17175024871;7203.243659415764;6959.686814093353;7019.508634395569;7043;7045.964770176142;7063.293670632947;7780.727674531393;8008.63961121748;8018.478798878084;8029.076656184526;8039.5176289422525;7922.04935407749;7971.322437592811;7984.680612775482;7994.680212791511;8008.879866802003;7732.49738766984;7904.841903161962;7941.841163176704;7947.642356093989;7947.682092716284;37621.43567846457;37981.24037519234;38211.235775284615;38230.617693822984;38238.23523529542;3500.6499350065046;3531.5408996830506;3539.02676763892;3539.3275277697153;3545.3972824619877;30672;30729.846350768326;30891.38217235665;30901;30902.845485772654;5029.346184995964;5037.042961837239;5058.266551350071;5075.720835354054;5103.209002604603;179306.10346948443;191706;193009;193986;195380;41435.58564414378;49847.75076124669;50530;50674.746626266635;50825.23762143571;52965.47034529605;53019;53091.46908530865;53347.19979200315;53469.732651337275;16623.50129496117;16704.08127552972;16817.41139060127;16836.831631683526;16872.83127168737;13773.655658608615;13840.100393474391;13869.583912482642;13903.582892513241;13919.721605568035;14696.11823290606;14821.851781482263;14851.331690073972;14947.327370268364;15025.323860426308;5945.821625351246;6025.879482410327;6107.908381374283;6114.2742982718655;6115.357887421846;991.4249735153594;996.6262651505685;999.3853779925439;999.6401295533602;999.8500224966314;22085;22392.888035559718;22405.43986400353;22638.320850374515;22792.544149116926;1090.0679918669582;1097.9505922233518;1099.7470581766238;1105.2539535813294;1122.955081796719;11940.462679179458;12031.398430078461;12053.397330133459;12056.75886482283;12162.452689628812;239027;241210;241970;242433;242854;53667;53691.463085369425;53733.46266537363;53893.73053134709;53894;1412.3432603839265;1453.989477313257;1456.4584799524896;1462.648964248594;1463.0782606957762;22100.88949555242;22157.224497142524;22225.77774222237;22458.438539036;22586.20948266803;34720.652793471745;37209.62790372062;37234.25531489301;37260.6273937268;37276.62723372732;193729;195427;196181;196553;196572;31862.681373186897;31940;32328.83835580901;35750.8212458936;35756.4636530447;44016;44213.33679994867;44268.778656106515;44517.55482445134;44591.33113003372;31705.36589268248;31852;31886.681133188373;32344;32562;31574.526382104745;31659.20851978719;31692.524612130375;31808.840955795073;31820.363592728478;35996.8200159008;36065.45901811423;36184.63815361918;36330.81834590916;36416;30805;31218.531722023734;31264.21839453941;31385.901493447618;31707;23066;23226.070957161523;23354;23473.41316467102;23602.409939750956 14:37:58 INFO - PID 5134 | |2;jquery.html;571.400029968533;581.828360633613;586.4723043681732;591.2343515147884;619.0806652121598;21382.25162119326;22365.217217397396;22377.77622223777;22379.44051398716;22412.66381004282;22736.65895011576;23286.76713232867;25044.74955250447;25287.11495097672;25323.493530129388;6567.770128045531;6798.660066996655;6801.727930882758;6802.625855577941;6803.319668033207;6403.967980160092;6684.629650921574;6778.389944904958;6784.491163162757;6799.660016999155;6661.200655921296;6663.2337281212795;6666.3000384959605;6667.766628168015;6669.366410191048;6651.201855777289;6659.101021362127;6659.966700166517;6668.666566671647;6670.566413183176;5908.734106965197;6113.174721412642;6122.908156377658;6190.3190649028575;6198.721057552376;29119.85440072786;29404.852975735197;29562.113136605938;29694.851525742233;29702.554461683092;2054.578811343686;2075.0973326602975;2085.1450905128822;2088.4361222469847;2092.257248676728;18956.24175032997;19520;19694.606107877906;19781.60436791256;19841.206351745914;2464.7165575958816;2478.083109249571;2510.962335564968;2530.2788705218923;2531.3545046013332;113629;120169;127441;131003;137083.31458342844;14369.395358843769;14672.266386680836;14814.851851481348;15027.364738223167;15394.692106157814;23158.305250842503;23264.41838954006;23277.41806454852;23298.301050968497;23366;10226.181905447545;10228.897711022943;10325.380477171393;10370.377777333233;10404.375737455777;9372.109649583279;9389.295802814817;9444.150026497511;9449.574769135406;9476.38901386766;8894.243989260856;9072.138146876168;9171.678991235274;9200.585973631069;9404.811903762024;2673.6791585009923;2694.986525067362;2715.4026114254834;2748.945021099607;2783.3737409082796;961.1493827962164;961.9178424272693;970.7184916374173;977.4819345746765;990.8563258327646;19044;19124;19153.712694309597;19520;19668.213271469267;1026.9743256418649;1030.8041472120274;1052.9789404212027;1053.1469509697017;1074.0655629602295;10683.465826708634;10690.89309106899;10754;10921.617743378943;11041.779164416666;472671;473649;498229;504904;506468;115991.42004290094;119390;119731.40134299273;120219.39890300493;121432;1066.6586692258559;1155.8150695888617;1191.976160476803;1196.3240768965507;1208.2267348896712;19869.403917882486;20199.4950126248;20297.594048119252;20358.18567257293;20555.280565180747;54156;54367.16579036735;55081.449185509235;55130.72434637801;55498.16752748793;67937.66031169813;68679.31320686729;68850.31149688439;68915;68946.65526672335;14250.501232456814;14554;14603.561893143222;14618.195999220145;14631.195284259467;22645.88677056604;22754.886225568767;22940.4264893379;23087;23102.306930792103;18153.727694084864;18532.351367702067;18559.628807423505;18561.071946402895;18590.163442645386;16082.919585401998;16407.753883691992;16413.589660258112;16495.587610309838;16796.24416901267;17421.912890435466;18188.818111818713;18850;18905.527361816065;18924.810751892306;14256.71486570242;14333.713325733635;14349.928250358682;14365.497207597684;14390.992630515764;17930.28278868883;18079.09604519795;18245.908770456062;18252.634947301245;18321 14:37:58 INFO - PID 5134 | |3;mootools.html;1369.643892587927;1397.0709478197;1420.55962651578;1423.1887823940358;1432.9856701432982;12236;12801.871981280172;12827.42276597553;14938.103713777155;15318.46385376512;14734.115953042825;14965.775513367309;14966.32651530681;14988.700225995473;15002.099874007567;2138.796814302643;2187.103287652063;2191.999999999992;2195.056125865877;2196.769339219383;2708.2281549758322;2809.19937817722;2812.086072026589;2812.4656315300026;2815.0569559197693;2729.181245626314;2736.2338545207276;2754.490419272441;2759.4756996170663;2767.6540432445945;2679.892804287836;2695.17797071893;2729.4541091781625;2732.53546897027;2751.2709132080013;2632.3550730071065;2647.444036752265;2654.2302732207636;2659.8670066496597;2665.0539058634295;14361.640958976004;14538.709225815424;14605.853941460555;14671.193084380355;14671.55985320442;7632.771016869503;7864.213578642147;7867.9606601966625;7873.173316801768;7874.5668988205625;5092.949070509285;5169.327987361657;5264.131418315973;5319.946800531984;5338.039152952467;1788.5439213000732;1790.4270633397189;1805.1696219738935;1833.51411875853;1842.6314737052644;24453;24686.382840428952;24697.753022469726;24776.752232477804;24803.25590232278;6458.838529036811;6534.085228068075;6753.15585551806;6818.147731533559;6828.624425656537;16107;16123.435679751152;16196.514104576881;16308.184590770414;16323.591910202102;7414.703411863465;7445.329920307198;7616.543007419573;7617.428692847948;7623.504472209289;6108.083787431832;6220.906686399708;7270.236625154388;7281.890771638429;7302.780916572511;5219.582433405394;5348.759305831247;5383.2194331821665;5431.456854314616;5436.075867102602;1464.875485583716;1515.7423238049557;1523.1622607565794;1550.046721266413;1566.0994927916493;913.899471058183;915.2220612479472;926.0091701878924;932.463833295844;933.4819175357742;7547.547147171187;7565.43259255558;7567.281108294703;7578.242175782378;7614;721.4300702445009;730.4302643937737;730.6711979609197;731.9634018299064;742.6138408027904;5174.715390653475;5207.661502002357;5324.201369794562;5341.866453338697;5347.0909945309395;20043.59912801736;20184.394468165978;20578.27976020862;20830.479238019172;20996.895015524824;20396;20667.896660516602;20737;20754.06606702702;20762;10669.57321707123;11232.719182020515;11292.322460652387;11303.717407064889;11320.26418282809;8150.429469937285;8193.877091843508;8220.753377398687;8228.25945664893;8319.334453243606;16761.91619041897;17365.56586085358;17542.298308067533;17591.032493212995;17644.382446614305;18022;18138.274469021093;18345.357912473;18624.161912714233;18680.252789888797;9671.274654400808;10595.523201456108;10723.839142413026;10827.296225745302;10842.728931776453;12103.878961210276;13433.66415839612;13577.524786632815;13637.795433068313;13670.31648417595;10963.671089867317;10985.395803230898;10994.615188468366;11033.393163376091;11139.777204456028;10779.784404312026;10793.838092428463;10954.849740777103;11004.669859904216;11261.436928153396;9733.659321923982;9780.951095244764;9880.604775808888;9892.703218903445;9900;7174.67713952863;7197.532160409557;7199.388052015428;7224.638768061681;7296.817579560341;6876.449884009167;6897.41371983387;6962.094927659372;6970.825729356807;7038.788836334918 14:37:58 INFO - PID 5134 | |4;prototype.html;508.9261657901829;521.9660722053069;528.9308393323234;537.5430883748811;544.3413469701661;6361.650109243989;6388.48892088633;6461.256955450113;6464.127342808725;6486.772962946297;6264.968675156618;6323.336049714782;6326.968365158168;6335.334789847069;6368.586041907284;5286.471352864702;5296.179092240709;5302;5314.840554783353;5368.141097424413;4974.726390048546;5110.540051395374;5223.8432847014465;5273.868153296179;5286.207068939651;5045.990801839628;5081.2886195932415;5081.8475445736685;5107.744612769366;5123.871903202431;5019.247112933052;5065.493450654942;5067.923981140286;5074.162763144077;5103.2855400244;4511.932321015187;4588;4589.24277494213;4614.792334344963;4626;6185.226846644169;6192;6196.442320191204;6215.595986260879;6258.280297765769;5654.660720356792;5820.767169313222;5828.795992140297;5851.473367396955;5883.79406720767;6649.734010639569;6672.633005184713;6699.7456615049305;6728.899066514006;6768.916973284251;4942;5011.098002359574;5026.47222041684;5031.094403007457;5047.520485553867;6782.559133656297;6785.355391237825;6799.626020568867;6862.382385585372;6956.582605043714;5098;5103.260027296022;5123.897522049539;5127.6154288428515;5149.639525233272;5097.64316497849;5138.203578445347;5138.922916156261;5150.201718733602;5230.529252367305;5733.426657334317;5772.509336706343;5825.475707186373;5833.329167145833;5842.2405087338375;6702.865942681119;6724.562903411263;6727.192736871509;6739.292374300726;6798.082258895085;1789.9552511187064;1794.2015802967815;1796.9820301797076;1811.619559892411;1812.546863284179;1154.503563467717;1228.846394200725;1246.3269834289383;1254.861965183829;1264.121435602266;5744.104445532964;5745.626534275259;5810.88378232433;5824.796132135355;5856.238688970407;6643.236027856762;6719.496037797185;6732.865342693217;6768.357006084414;6776.69504872282;5741.363711342247;5750.712464376765;5758.2514273144225;5767.5674324425;5785.537157027427;3566.8777077638765;3576.445650924111;3607.909802254912;3611.6930060944587;3626.256617393428;5907.763689452459;6512.5766825156215;6562.9326762069795;6575.520507885783;6618.139641846529;6836.4530837532875;6859.897101543481;6877.6217308047535;6889.175438070367;7055.506114571931;6450.903236451457;6500.674966251669;6518.120053792677;6536.869262614816;6537.248216455168;6712;6753.628550429774;6801.89797153053;6834.45324374039;6846.383825455733;6751.358620931003;6810.284920083518;6846.931530684828;6863.897041544481;6882.174139103339;6568.784774816674;6592.80221593353;6595.835104122435;6630.502712296694;6638.800835974928;5065.087574644521;5081.974590127025;5092.9814037193455;5121.513456221653;5143.765496280791;6565.638889861103;6588.769393071219;6689.966550167218;6708.295628958889;6803.149606299213;6578.309277525982;6586.4401525870835;6613.115262150264;6623.6688165592495;6628.370304821035;6461.515386345949;6545.934540654533;6562.8359291018105;6578.539502234989;6619.569727967667;6497.545171837926;6539.874540774173;6562.146920900444;6579.407853293226;6620.337966203341;6616.7022483987375;6658.6337751424135;6662.467002639681;6677.165354330708;6728.158980127484;6430.356964303533;6470;6473.385028422293;6477.578957367756;6483.546151769519 14:37:58 INFO - PID 5134 | |5;yui.html;906.7461110888953;917.2616044084511;923.8845144356956;931.585444477208;938.0994245524296;19617;20506.2822801202;20522.179112835507;20524.28165014225;20543;18639.53401164968;18723.251069957187;18765.7185142223;18780.342688005923;18828.152733127008;975.4391225045591;975.6926568131051;975.8536219567051;975.9365641233333;976.9658061967832;979.7011911367031;981.1562056631292;981.6367943860781;984.5569493727851;986.5363279258737;1002.6190047781832;1019.6176433837311;1031.3708637730979;1032.0144262229553;1032.2825636182856;1022.2435397805644;1023.81059503992;1023.9948800255951;1024.5748014573942;1024.9128824049965;736.4329466310911;748.7416841189781;749.3555542233674;754.1478129713444;760.8212070163507;20421.8978905106;20650.896745516176;20671.689924650993;20714;20725.896370518203;21117;21233.575328493498;21641.891790541104;21840.563188736138;21850.89074554633;15675.059496430136;15855.127967961758;15880.523584292374;16027.91986040074;16458.670826583402;3703.703703703697;3710.591834898159;3723.478712980185;3725.9068523287133;3734.1411475360546;102740.48629756879;105306;140080;142121;145597;17206.741898871525;17483;17573.560660983323;17643;17994.820051799314;30918.381632367677;31671.683283166873;32291;32299.51550726741;32309.838450807594;9091.227245684191;9220;9247.213986811197;9258.537073146315;9281.39670921388;4112.383142528644;4159.188958153191;4183.974926143053;4186;4186.6859985501205;5365.678059316376;5403.5136837684795;5431.131019036935;5459.863503412367;5471.562275017989;1063.8723353197665;1088.5264909764278;1099.4228030284164;1099.8350247462783;1103.1450625765096;678.575890068707;687.2165731066588;689.152342618572;690.0959742737031;691.6541729135432;12288.754224915452;12765.872341276654;12891.140151061047;13732.45070197201;13845.376958036912;681.7034589953386;683.0505597219868;689.0731965506386;692.1210063219784;705.9788206353818;5562.360328562187;5630.887382252332;5645.491905728422;5669.546436285087;5760.337561180434;127442.36278818733;128923;130211;131951;132366;112016.43991779989;112790;113044.43477782559;114104.42947785374;114525.4273728626;95854.52072739591;97164.51417742866;99486.5025674867;99504;100706.99293007122;10299.588016479556;10471.21465890046;10539.789204216026;10776.137908967106;10839.295445795999;41483.37774933318;42183.156336873704;42463.3630495549;42781.35827962645;43154;55372.44627553673;55449.44550554604;56206.71896640491;56367.43632563622;57156;15227.771583426036;15507.069575825486;15684.68630627404;15702.528924132293;15904.76142857835;18157.45527634173;18172.818271817643;18229.54426139357;18276.634467310847;18426.81573184251;16715.582110447336;16810.831891680926;16866.325346985985;16879.07165105931;16960.830391695927;15789;15871.920640396724;15994.12032338186;15997;16025;15666.138362390178;15969.920150399174;16026.198690065683;16119.355225790838;16185.514434566981;11882.702932426759;12411.317377544321;12438.751224975269;12520.874791252336;12716.36418179106;11161.944190278997;11170.553177872795;11276.774464510829;11361.261518001304;11458.484368203284 14:37:58 INFO - PID 5134 | __end_tp_report 14:37:58 INFO - PID 5134 | __start_cc_report 14:37:58 INFO - PID 5134 | _x_x_mozilla_cycle_collect,291 14:37:58 INFO - PID 5134 | __end_cc_report 14:37:58 INFO - PID 5134 | __startTimestamp1507066678516__endTimestamp 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | ------- Summary: start ------- 14:37:58 INFO - PID 5134 | Number of tests: 6 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | [#0] dojo.html Cycles:165 Average:6247.78 Median:6543.31 stddev:902.47 (13.8%) stddev-sans-first:871.99 14:37:58 INFO - PID 5134 | Values: 3153.9 3167.0 3187.9 3189.3 3215.2 6188.2 6329.8 6339.7 6401.0 6465.0 6325.5 6339.8 6457.6 6467.9 6477.7 917.6 5580.4 5923.1 5951.1 6002.0 5769.0 5829.7 5855.9 5864.9 5945.1 5918.9 5943.3 5958.2 5969.4 5982.9 5875.3 5886.4 5951.9 5965.6 5979.6 5705.4 5762.5 5764.6 5866.4 5920.8 6272.7 6285.4 6368.7 6413.0 6420.0 6785.4 6886.2 6890.0 6892.7 6899.7 6784.7 6882.7 6959.9 6969.4 6970.5 6206.0 6350.4 6356.3 6362.7 6366.2 7004.2 7071.9 7166.8 7167.4 7173.4 6702.7 6777.1 6800.7 6802.8 6803.7 6808.3 6809.2 6824.0 6899.7 6904.4 6631.2 6705.8 6796.7 6879.0 6899.7 6858.3 6867.5 6980.4 6983.0 6990.8 5005.8 5075.3 5089.0 5091.8 5101.7 4346.9 4401.4 4460.4 4464.3 4472.8 5821.4 5845.2 5858.4 5861.3 5864.0 6918.6 6926.4 6928.2 6938.8 6942.6 5845.1 5845.4 5930.9 5934.2 5938.0 5665.1 5804.1 5843.8 5909.0 5914.7 6458.6 6496.7 6543.3 6590.3 6632.2 6847.0 6882.9 6941.0 6976.1 6978.3 6556.3 6598.5 6601.5 6693.1 6703.6 6719.2 6792.0 6799.6 6805.2 6812.3 6918.1 6973.1 6973.5 6976.4 6981.7 6527.9 6584.8 6640.9 6691.5 6717.5 6485.8 6609.4 6650.5 6654.6 6728.7 6591.1 6625.9 6626.2 6630.5 6682.6 6584.8 6706.3 6754.4 6787.2 6795.4 6304.8 6387.5 6387.8 6430.3 6575.9 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | [#1] ext.html Cycles:180 Average:37757.62 Median:35876.64 stddev:54613.96 (152.2%) stddev-sans-first:54696.92 14:37:58 INFO - PID 5134 | Values: 868.9 878.3 880.9 894.3 902.7 40394.2 40924.8 40928.8 40932.0 41162.6 37165.0 37336.6 37418.3 37432.6 37653.0 7073.0 7192.2 7194.6 7202.2 7203.2 6959.7 7019.5 7043.0 7046.0 7063.3 7780.7 8008.6 8018.5 8029.1 8039.5 7922.0 7971.3 7984.7 7994.7 8008.9 7732.5 7904.8 7941.8 7947.6 7947.7 37621.4 37981.2 38211.2 38230.6 38238.2 3500.6 3531.5 3539.0 3539.3 3545.4 30672.0 30729.8 30891.4 30901.0 30902.8 5029.3 5037.0 5058.3 5075.7 5103.2 179306.1 191706.0 193009.0 193986.0 195380.0 41435.6 49847.8 50530.0 50674.7 50825.2 52965.5 53019.0 53091.5 53347.2 53469.7 16623.5 16704.1 16817.4 16836.8 16872.8 13773.7 13840.1 13869.6 13903.6 13919.7 14696.1 14821.9 14851.3 14947.3 15025.3 5945.8 6025.9 6107.9 6114.3 6115.4 991.4 996.6 999.4 999.6 999.9 22085.0 22392.9 22405.4 22638.3 22792.5 1090.1 1098.0 1099.7 1105.3 1123.0 11940.5 12031.4 12053.4 12056.8 12162.5 239027.0 241210.0 241970.0 242433.0 242854.0 53667.0 53691.5 53733.5 53893.7 53894.0 1412.3 1454.0 1456.5 1462.6 1463.1 22100.9 22157.2 22225.8 22458.4 22586.2 34720.7 37209.6 37234.3 37260.6 37276.6 193729.0 195427.0 196181.0 196553.0 196572.0 31862.7 31940.0 32328.8 35750.8 35756.5 44016.0 44213.3 44268.8 44517.6 44591.3 31705.4 31852.0 31886.7 32344.0 32562.0 31574.5 31659.2 31692.5 31808.8 31820.4 35996.8 36065.5 36184.6 36330.8 36416.0 30805.0 31218.5 31264.2 31385.9 31707.0 23066.0 23226.1 23354.0 23473.4 23602.4 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | [#2] jquery.html Cycles:180 Average:34448.85 Median:22691.27 stddev:82453.32 (363.4%) stddev-sans-first:82645.39 14:37:58 INFO - PID 5134 | Values: 571.4 581.8 586.5 591.2 619.1 21382.3 22365.2 22377.8 22379.4 22412.7 22736.7 23286.8 25044.7 25287.1 25323.5 6567.8 6798.7 6801.7 6802.6 6803.3 6404.0 6684.6 6778.4 6784.5 6799.7 6661.2 6663.2 6666.3 6667.8 6669.4 6651.2 6659.1 6660.0 6668.7 6670.6 5908.7 6113.2 6122.9 6190.3 6198.7 29119.9 29404.9 29562.1 29694.9 29702.6 2054.6 2075.1 2085.1 2088.4 2092.3 18956.2 19520.0 19694.6 19781.6 19841.2 2464.7 2478.1 2511.0 2530.3 2531.4 113629.0 120169.0 127441.0 131003.0 137083.3 14369.4 14672.3 14814.9 15027.4 15394.7 23158.3 23264.4 23277.4 23298.3 23366.0 10226.2 10228.9 10325.4 10370.4 10404.4 9372.1 9389.3 9444.2 9449.6 9476.4 8894.2 9072.1 9171.7 9200.6 9404.8 2673.7 2695.0 2715.4 2748.9 2783.4 961.1 961.9 970.7 977.5 990.9 19044.0 19124.0 19153.7 19520.0 19668.2 1027.0 1030.8 1053.0 1053.1 1074.1 10683.5 10690.9 10754.0 10921.6 11041.8 472671.0 473649.0 498229.0 504904.0 506468.0 115991.4 119390.0 119731.4 120219.4 121432.0 1066.7 1155.8 1192.0 1196.3 1208.2 19869.4 20199.5 20297.6 20358.2 20555.3 54156.0 54367.2 55081.4 55130.7 55498.2 67937.7 68679.3 68850.3 68915.0 68946.7 14250.5 14554.0 14603.6 14618.2 14631.2 22645.9 22754.9 22940.4 23087.0 23102.3 18153.7 18532.4 18559.6 18561.1 18590.2 16082.9 16407.8 16413.6 16495.6 16796.2 17421.9 18188.8 18850.0 18905.5 18924.8 14256.7 14333.7 14349.9 14365.5 14391.0 17930.3 18079.1 18245.9 18252.6 18321.0 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | [#3] mootools.html Cycles:180 Average:8943.69 Median:2650.84 stddev:6290.66 (237.3%) stddev-sans-first:6282.57 14:37:58 INFO - PID 5134 | Values: 1369.6 1397.1 1420.6 1423.2 1433.0 12236.0 12801.9 12827.4 14938.1 15318.5 14734.1 14965.8 14966.3 14988.7 15002.1 2138.8 2187.1 2192.0 2195.1 2196.8 2708.2 2809.2 2812.1 2812.5 2815.1 2729.2 2736.2 2754.5 2759.5 2767.7 2679.9 2695.2 2729.5 2732.5 2751.3 2632.4 2647.4 2654.2 2659.9 2665.1 14361.6 14538.7 14605.9 14671.2 14671.6 7632.8 7864.2 7868.0 7873.2 7874.6 5092.9 5169.3 5264.1 5319.9 5338.0 1788.5 1790.4 1805.2 1833.5 1842.6 24453.0 24686.4 24697.8 24776.8 24803.3 6458.8 6534.1 6753.2 6818.1 6828.6 16107.0 16123.4 16196.5 16308.2 16323.6 7414.7 7445.3 7616.5 7617.4 7623.5 6108.1 6220.9 7270.2 7281.9 7302.8 5219.6 5348.8 5383.2 5431.5 5436.1 1464.9 1515.7 1523.2 1550.0 1566.1 913.9 915.2 926.0 932.5 933.5 7547.5 7565.4 7567.3 7578.2 7614.0 721.4 730.4 730.7 732.0 742.6 5174.7 5207.7 5324.2 5341.9 5347.1 20043.6 20184.4 20578.3 20830.5 20996.9 20396.0 20667.9 20737.0 20754.1 20762.0 10669.6 11232.7 11292.3 11303.7 11320.3 8150.4 8193.9 8220.8 8228.3 8319.3 16761.9 17365.6 17542.3 17591.0 17644.4 18022.0 18138.3 18345.4 18624.2 18680.3 9671.3 10595.5 10723.8 10827.3 10842.7 12103.9 13433.7 13577.5 13637.8 13670.3 10963.7 10985.4 10994.6 11033.4 11139.8 10779.8 10793.8 10954.8 11004.7 11261.4 9733.7 9781.0 9880.6 9892.7 9900.0 7174.7 7197.5 7199.4 7224.6 7296.8 6876.4 6897.4 6962.1 6970.8 7038.8 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | [#4] prototype.html Cycles:180 Average:5597.47 Median:6261.62 stddev:1553.29 (24.8%) stddev-sans-first:1509.97 14:37:58 INFO - PID 5134 | Values: 508.9 522.0 528.9 537.5 544.3 6361.7 6388.5 6461.3 6464.1 6486.8 6265.0 6323.3 6327.0 6335.3 6368.6 5286.5 5296.2 5302.0 5314.8 5368.1 4974.7 5110.5 5223.8 5273.9 5286.2 5046.0 5081.3 5081.8 5107.7 5123.9 5019.2 5065.5 5067.9 5074.2 5103.3 4511.9 4588.0 4589.2 4614.8 4626.0 6185.2 6192.0 6196.4 6215.6 6258.3 5654.7 5820.8 5828.8 5851.5 5883.8 6649.7 6672.6 6699.7 6728.9 6768.9 4942.0 5011.1 5026.5 5031.1 5047.5 6782.6 6785.4 6799.6 6862.4 6956.6 5098.0 5103.3 5123.9 5127.6 5149.6 5097.6 5138.2 5138.9 5150.2 5230.5 5733.4 5772.5 5825.5 5833.3 5842.2 6702.9 6724.6 6727.2 6739.3 6798.1 1790.0 1794.2 1797.0 1811.6 1812.5 1154.5 1228.8 1246.3 1254.9 1264.1 5744.1 5745.6 5810.9 5824.8 5856.2 6643.2 6719.5 6732.9 6768.4 6776.7 5741.4 5750.7 5758.3 5767.6 5785.5 3566.9 3576.4 3607.9 3611.7 3626.3 5907.8 6512.6 6562.9 6575.5 6618.1 6836.5 6859.9 6877.6 6889.2 7055.5 6450.9 6500.7 6518.1 6536.9 6537.2 6712.0 6753.6 6801.9 6834.5 6846.4 6751.4 6810.3 6846.9 6863.9 6882.2 6568.8 6592.8 6595.8 6630.5 6638.8 5065.1 5082.0 5093.0 5121.5 5143.8 6565.6 6588.8 6690.0 6708.3 6803.1 6578.3 6586.4 6613.1 6623.7 6628.4 6461.5 6545.9 6562.8 6578.5 6619.6 6497.5 6539.9 6562.1 6579.4 6620.3 6616.7 6658.6 6662.5 6677.2 6728.2 6430.4 6470.0 6473.4 6477.6 6483.5 14:37:58 INFO - PID 5134 | 14:37:58 INFO - PID 5134 | [#5] yui.html Cycles:180 Average:24465.78 Median:19222.58 stddev:35371.58 (184.0%) stddev-sans-first:35426.57 14:37:58 INFO - PID 5134 | Values: 906.7 917.3 923.9 931.6 938.1 19617.0 20506.3 20522.2 20524.3 20543.0 18639.5 18723.3 18765.7 18780.3 18828.2 975.4 975.7 975.9 975.9 977.0 979.7 981.2 981.6 984.6 986.5 1002.6 1019.6 1031.4 1032.0 1032.3 1022.2 1023.8 1024.0 1024.6 1024.9 736.4 748.7 749.4 754.1 760.8 20421.9 20650.9 20671.7 20714.0 20725.9 21117.0 21233.6 21641.9 21840.6 21850.9 15675.1 15855.1 15880.5 16027.9 16458.7 3703.7 3710.6 3723.5 3725.9 3734.1 102740.5 105306.0 140080.0 142121.0 145597.0 17206.7 17483.0 17573.6 17643.0 17994.8 30918.4 31671.7 32291.0 32299.5 32309.8 9091.2 9220.0 9247.2 9258.5 9281.4 4112.4 4159.2 4184.0 4186.0 4186.7 5365.7 5403.5 5431.1 5459.9 5471.6 1063.9 1088.5 1099.4 1099.8 1103.1 678.6 687.2 689.2 690.1 691.7 12288.8 12765.9 12891.1 13732.5 13845.4 681.7 683.1 689.1 692.1 706.0 5562.4 5630.9 5645.5 5669.5 5760.3 127442.4 128923.0 130211.0 131951.0 132366.0 112016.4 112790.0 113044.4 114104.4 114525.4 95854.5 97164.5 99486.5 99504.0 100707.0 10299.6 10471.2 10539.8 10776.1 10839.3 41483.4 42183.2 42463.4 42781.4 43154.0 55372.4 55449.4 56206.7 56367.4 57156.0 15227.8 15507.1 15684.7 15702.5 15904.8 18157.5 18172.8 18229.5 18276.6 18426.8 16715.6 16810.8 16866.3 16879.1 16960.8 15789.0 15871.9 15994.1 15997.0 16025.0 15666.1 15969.9 16026.2 16119.4 16185.5 11882.7 12411.3 12438.8 12520.9 12716.4 11161.9 11170.6 11276.8 11361.3 11458.5 14:37:58 INFO - PID 5134 | -------- Summary: end -------- 14:37:58 INFO - PID 5134 | 14:37:59 INFO - TEST-INFO | 5134: exit 0 14:37:59 INFO - TEST-OK | dromaeo_css | took 1108778ms 14:37:59 INFO - TEST-START | kraken 14:37:59 INFO - Initialising browser for kraken test... 14:37:59 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:52223/getInfo.html -profile /tmp/tmp2pyNaM/profile 14:37:59 INFO - TEST-INFO | started process 5534 (/builds/slave/test/build/application/firefox/firefox http://localhost:52223/getInfo.html) 14:38:04 INFO - TEST-INFO | 5534: exit 0 14:38:04 INFO - Browser initialized. 14:38:04 INFO - Running cycle 1/1 for kraken test... 14:38:04 INFO - Using env: {'DISPLAY': ':0', 14:38:04 INFO - 'HOME': '/home/cltbld', 14:38:04 INFO - 'JSGC_DISABLE_POISONING': '1', 14:38:04 INFO - 'LANG': 'en_US.UTF-8', 14:38:04 INFO - 'LANGUAGE': 'en_US:en', 14:38:04 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 14:38:04 INFO - 'LOGNAME': 'cltbld', 14:38:04 INFO - 'MAIL': '/var/mail/cltbld', 14:38:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:38:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:38:04 INFO - 'MOZ_CRASHREPORTER': '1', 14:38:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:04 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 14:38:04 INFO - 'MOZ_NO_REMOTE': '1', 14:38:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:38:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:38:04 INFO - 'NO_EM_RESTART': '1', 14:38:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:38:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:04 INFO - 'PWD': '/builds/slave/test', 14:38:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:38:04 INFO - 'RUST_BACKTRACE': 'full', 14:38:04 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:38:04 INFO - 'SHELL': '/bin/bash', 14:38:04 INFO - 'SHLVL': '1', 14:38:04 INFO - 'TERM': 'linux', 14:38:04 INFO - 'TMOUT': '86400', 14:38:04 INFO - 'USER': 'cltbld', 14:38:04 INFO - 'XDG_SESSION_COOKIE': 'fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757', 14:38:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:04 INFO - '_': '/tools/buildbot/bin/python'} 14:38:04 INFO - TEST-INFO | started process 5804 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2pyNaM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:05 INFO - PID 5804 | 14:38:05 INFO - PID 5804 | (/builds/slave/test/build/application/firefox/firefox:5854): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 14:38:05 INFO - PID 5804 | 14:38:15 INFO - PID 5804 | RSS: Main: 155484160 14:38:15 INFO - PID 5804 | 14:38:18 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52223/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:19 INFO - PID 5804 | RSS: Main: 161738752 14:38:19 INFO - PID 5804 | 14:38:21 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52223/tests/kraken/driver.html?testName=audio-dft) 14:38:22 INFO - PID 5804 | RSS: Main: 162172928 14:38:22 INFO - PID 5804 | 14:38:25 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52223/tests/kraken/driver.html?testName=audio-fft) 14:38:25 INFO - PID 5804 | RSS: Main: 157257728 14:38:25 INFO - PID 5804 | 14:38:28 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52223/tests/kraken/driver.html?testName=audio-oscillator) 14:38:28 INFO - PID 5804 | RSS: Main: 158199808 14:38:28 INFO - PID 5804 | 14:38:30 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:31 INFO - PID 5804 | RSS: Main: 158298112 14:38:31 INFO - PID 5804 | 14:38:34 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52223/tests/kraken/driver.html?testName=imaging-darkroom) 14:38:35 INFO - PID 5804 | RSS: Main: 160370688 14:38:35 INFO - PID 5804 | 14:38:38 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52223/tests/kraken/driver.html?testName=imaging-desaturate) 14:38:39 INFO - PID 5804 | RSS: Main: 160911360 14:38:39 INFO - PID 5804 | 14:38:42 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52223/tests/kraken/driver.html?testName=json-parse-financial) 14:38:43 INFO - PID 5804 | RSS: Main: 161181696 14:38:43 INFO - PID 5804 | 14:38:44 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:38:45 INFO - PID 5804 | RSS: Main: 161013760 14:38:45 INFO - PID 5804 | 14:38:47 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:38:47 INFO - PID 5804 | RSS: Main: 161062912 14:38:47 INFO - PID 5804 | 14:38:49 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:38:50 INFO - PID 5804 | RSS: Main: 157847552 14:38:50 INFO - PID 5804 | 14:38:52 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:38:53 INFO - PID 5804 | RSS: Main: 158130176 14:38:53 INFO - PID 5804 | 14:38:56 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:38:57 INFO - PID 5804 | RSS: Main: 158248960 14:38:57 INFO - PID 5804 | 14:38:58 INFO - PID 5804 | Cycle 1(1): loaded http://localhost:52223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52223/tests/kraken/driver.html?testName=ai-astar) 14:38:59 INFO - PID 5804 | RSS: Main: 159563776 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | __start_tp_report 14:38:59 INFO - PID 5804 | _x_x_mozilla_page_load 14:38:59 INFO - PID 5804 | _x_x_mozilla_page_load_details 14:38:59 INFO - PID 5804 | |i|pagename|runs| 14:38:59 INFO - PID 5804 | |0;ai-astar;109;107;107;108;109;110;108;109;109;108 14:38:59 INFO - PID 5804 | |1;audio-beat-detection;117;118;117;115;117;117;123;121;120;120 14:38:59 INFO - PID 5804 | |2;audio-dft;162;163;162;162;163;164;163;170;168;167 14:38:59 INFO - PID 5804 | |3;audio-fft;76;76;75;74;74;73;74;75;73;115 14:38:59 INFO - PID 5804 | |4;audio-oscillator;80;81;79;79;83;79;79;79;79;80 14:38:59 INFO - PID 5804 | |5;imaging-gaussian-blur;113;114;113;117;116;117;114;114;115;114 14:38:59 INFO - PID 5804 | |6;imaging-darkroom;148;148;148;149;148;147;149;151;148;148 14:38:59 INFO - PID 5804 | |7;imaging-desaturate;99;93;98;95;95;94;94;95;95;94 14:38:59 INFO - PID 5804 | |8;json-parse-financial;55;55;56;53;52;55;53;53;51;52 14:38:59 INFO - PID 5804 | |9;json-stringify-tinderbox;41;37;37;37;36;37;37;37;37;37 14:38:59 INFO - PID 5804 | |10;stanford-crypto-aes;73;72;74;73;74;73;73;73;72;73 14:38:59 INFO - PID 5804 | |11;stanford-crypto-ccm;111;116;110;109;114;109;114;118;118;109 14:38:59 INFO - PID 5804 | |12;stanford-crypto-pbkdf2;171;163;160;163;168;168;173;158;168;164 14:38:59 INFO - PID 5804 | |13;stanford-crypto-sha256-iterative;65;68;65;67;67;66;78;65;74;68 14:38:59 INFO - PID 5804 | __end_tp_report 14:38:59 INFO - PID 5804 | __start_cc_report 14:38:59 INFO - PID 5804 | _x_x_mozilla_cycle_collect,756 14:38:59 INFO - PID 5804 | __end_cc_report 14:38:59 INFO - PID 5804 | __startTimestamp1507066739119__endTimestamp 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | ------- Summary: start ------- 14:38:59 INFO - PID 5804 | Number of tests: 14 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.00 stddev:0.97 (0.9%) stddev-sans-first:1.00 14:38:59 INFO - PID 5804 | Values: 109.0 107.0 107.0 108.0 109.0 110.0 108.0 109.0 109.0 108.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:119.00 stddev:2.42 (2.0%) stddev-sans-first:2.50 14:38:59 INFO - PID 5804 | Values: 117.0 118.0 117.0 115.0 117.0 117.0 123.0 121.0 120.0 120.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#2] audio-dft Cycles:10 Average:164.40 Median:163.50 stddev:2.88 (1.8%) stddev-sans-first:2.92 14:38:59 INFO - PID 5804 | Values: 162.0 163.0 162.0 162.0 163.0 164.0 163.0 170.0 168.0 167.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#3] audio-fft Cycles:10 Average:78.50 Median:74.50 stddev:12.87 (17.3%) stddev-sans-first:13.62 14:38:59 INFO - PID 5804 | Values: 76.0 76.0 75.0 74.0 74.0 73.0 74.0 75.0 73.0 115.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#4] audio-oscillator Cycles:10 Average:79.80 Median:79.50 stddev:1.32 (1.7%) stddev-sans-first:1.39 14:38:59 INFO - PID 5804 | Values: 80.0 81.0 79.0 79.0 83.0 79.0 79.0 79.0 79.0 80.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#5] imaging-gaussian-blur Cycles:10 Average:114.70 Median:114.50 stddev:1.49 (1.3%) stddev-sans-first:1.45 14:38:59 INFO - PID 5804 | Values: 113.0 114.0 113.0 117.0 116.0 117.0 114.0 114.0 115.0 114.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#6] imaging-darkroom Cycles:10 Average:148.40 Median:148.00 stddev:1.07 (0.7%) stddev-sans-first:1.13 14:38:59 INFO - PID 5804 | Values: 148.0 148.0 148.0 149.0 148.0 147.0 149.0 151.0 148.0 148.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:1.87 (2.0%) stddev-sans-first:1.39 14:38:59 INFO - PID 5804 | Values: 99.0 93.0 98.0 95.0 95.0 94.0 94.0 95.0 95.0 94.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.65 (3.1%) stddev-sans-first:1.66 14:38:59 INFO - PID 5804 | Values: 55.0 55.0 56.0 53.0 52.0 55.0 53.0 53.0 51.0 52.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#9] json-stringify-tinderbox Cycles:10 Average:37.30 Median:37.00 stddev:1.34 (3.6%) stddev-sans-first:0.33 14:38:59 INFO - PID 5804 | Values: 41.0 37.0 37.0 37.0 36.0 37.0 37.0 37.0 37.0 37.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#10] stanford-crypto-aes Cycles:10 Average:73.00 Median:73.00 stddev:0.67 (0.9%) stddev-sans-first:0.71 14:38:59 INFO - PID 5804 | Values: 73.0 72.0 74.0 73.0 74.0 73.0 73.0 73.0 72.0 73.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#11] stanford-crypto-ccm Cycles:10 Average:112.80 Median:114.00 stddev:3.68 (3.2%) stddev-sans-first:3.84 14:38:59 INFO - PID 5804 | Values: 111.0 116.0 110.0 109.0 114.0 109.0 114.0 118.0 118.0 109.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:168.00 stddev:4.79 (2.9%) stddev-sans-first:4.66 14:38:59 INFO - PID 5804 | Values: 171.0 163.0 160.0 163.0 168.0 168.0 173.0 158.0 168.0 164.0 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.50 stddev:4.32 (6.4%) stddev-sans-first:4.42 14:38:59 INFO - PID 5804 | Values: 65.0 68.0 65.0 67.0 67.0 66.0 78.0 65.0 74.0 68.0 14:38:59 INFO - PID 5804 | -------- Summary: end -------- 14:38:59 INFO - PID 5804 | 14:38:59 INFO - PID 5804 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 14:38:59 INFO - TEST-INFO | 5804: exit 0 14:38:59 INFO - TEST-OK | kraken | took 60837ms 14:38:59 INFO - SUITE-END | took 1169s 14:39:00 INFO - Completed test suite (00:19:30) 14:39:00 INFO - javascript benchmark 14:39:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8503.909709381363, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3153.9369212615734, 3166.9524957125645, 3187.8565464554094, 3189.2824114574237, 3215.228345197152, 6188.226471691039, 6329.841753956153, 6339.746410143594, 6400.967995160024, 6464.967675161623, 6325.493960483162, 6339.809805705825, 6457.6448295343735, 6467.935320646792, 6477.74089036438, 917.5778225181973, 5580.414056524068, 5923.05231163014, 5951.077582974646, 6002.009668404708, 5769.0, 5829.708514574276, 5855.941440585603, 5864.941350586503, 5945.137954996523, 5918.852028699295, 5943.3165186003735, 5958.165856780054, 5969.403059694039, 5982.8504287392725, 5875.294964604275, 5886.411358864122, 5951.880962380771, 5965.612235204741, 5979.641221526679, 5705.4294570543025, 5762.4525670061275, 5764.625299355528, 5866.354700982888, 5920.792772252994, 6272.749090036393, 6285.402886725754, 6368.745250189987, 6412.967935160341, 6419.967900160469, 6785.355391237825, 6886.208086070116, 6889.965550172267, 6892.689828957709, 6899.689513971884, 6784.694688739019, 6882.69027893746, 6959.8956015659805, 6969.407600353976, 6970.477214208955, 6205.968970155165, 6350.396712312323, 6356.300806911236, 6362.745490180388, 6366.172397588331, 7004.15950085993, 7071.92928070723, 7166.8208294792, 7167.426605871622, 7173.426125909915, 6702.664866756642, 6777.085093512313, 6800.65996700163, 6802.795916122524, 6803.65981700913, 6808.2510923798345, 6809.216940051859, 6824.0, 6899.689513971783, 6904.447644188453, 6631.23740769808, 6705.765298214539, 6796.694148763318, 6879.0025446311, 6899.689513971783, 6858.348456896587, 6867.450603951672, 6980.371766541036, 6982.965085174542, 6990.790276291719, 5005.799768009311, 5075.289459475676, 5089.0, 5091.821786237538, 5101.69389836611, 4346.891327716832, 4401.427814384109, 4460.375547423363, 4464.2633965396, 4472.843450479218, 5821.388754180834, 5845.240118784531, 5858.443447872445, 5861.32594751609, 5864.03243464832, 6918.584884906922, 6926.376626103574, 6928.16861976566, 6938.826529336706, 6942.583444993316, 5845.1232315153065, 5845.357010728816, 5930.8813823723285, 5934.1692163097205, 5938.049912014057, 5665.093585026376, 5804.100364443518, 5843.766249350064, 5908.99547076998, 5914.733836977346, 6458.580192287486, 6496.740130394731, 6543.312952140052, 6590.275069742324, 6632.237292711304, 6847.041414202115, 6882.8623427530165, 6941.028256044158, 6976.093107896144, 6978.30216978288, 6556.2788093310655, 6598.505112116514, 6601.537892347682, 6693.09643198162, 6703.597784132968, 6719.227288861844, 6792.0, 6799.626020568916, 6805.217399999064, 6812.284710105368, 6918.135233095863, 6973.058637083929, 6973.47698922593, 6976.441884649317, 6981.685824137824, 6527.902081468686, 6584.8024559263295, 6640.9335906642245, 6691.498137639599, 6717.462602991846, 6485.805425837232, 6609.405153536205, 6650.534462587766, 6654.600723956578, 6728.7308507659145, 6591.143151390289, 6625.900611490735, 6626.237982632059, 6630.53586248958, 6682.599044057372, 6584.76953306632, 6706.295838936841, 6754.425873800782, 6787.21946976104, 6795.38841504267, 6304.810855674337, 6387.489000880011, 6387.840303992437, 6430.260520040255, 6575.934240657723], "value": 6178.640978756223, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [868.9087645797191, 878.3324673248329, 880.8766772651834, 894.3069121430892, 902.7021083042592, 40394.19211615766, 40924.795376023154, 40928.795356023176, 40932.0, 41162.58837411625, 37165.0, 37336.62663373366, 37418.25163496729, 37432.625673743256, 37653.0, 7073.0, 7192.172900116475, 7194.568325900436, 7202.17175024871, 7203.243659415764, 6959.686814093353, 7019.508634395569, 7043.0, 7045.964770176142, 7063.293670632947, 7780.727674531393, 8008.63961121748, 8018.478798878084, 8029.076656184526, 8039.5176289422525, 7922.04935407749, 7971.322437592811, 7984.680612775482, 7994.680212791511, 8008.879866802003, 7732.49738766984, 7904.841903161962, 7941.841163176704, 7947.642356093989, 7947.682092716284, 37621.43567846457, 37981.24037519234, 38211.235775284615, 38230.617693822984, 38238.23523529542, 3500.6499350065046, 3531.5408996830506, 3539.02676763892, 3539.3275277697153, 3545.3972824619877, 30672.0, 30729.846350768326, 30891.38217235665, 30901.0, 30902.845485772654, 5029.346184995964, 5037.042961837239, 5058.266551350071, 5075.720835354054, 5103.209002604603, 179306.10346948443, 191706.0, 193009.0, 193986.0, 195380.0, 41435.58564414378, 49847.75076124669, 50530.0, 50674.746626266635, 50825.23762143571, 52965.47034529605, 53019.0, 53091.46908530865, 53347.19979200315, 53469.732651337275, 16623.50129496117, 16704.08127552972, 16817.41139060127, 16836.831631683526, 16872.83127168737, 13773.655658608615, 13840.100393474391, 13869.583912482642, 13903.582892513241, 13919.721605568035, 14696.11823290606, 14821.851781482263, 14851.331690073972, 14947.327370268364, 15025.323860426308, 5945.821625351246, 6025.879482410327, 6107.908381374283, 6114.2742982718655, 6115.357887421846, 991.4249735153594, 996.6262651505685, 999.3853779925439, 999.6401295533602, 999.8500224966314, 22085.0, 22392.888035559718, 22405.43986400353, 22638.320850374515, 22792.544149116926, 1090.0679918669582, 1097.9505922233518, 1099.7470581766238, 1105.2539535813294, 1122.955081796719, 11940.462679179458, 12031.398430078461, 12053.397330133459, 12056.75886482283, 12162.452689628812, 239027.0, 241210.0, 241970.0, 242433.0, 242854.0, 53667.0, 53691.463085369425, 53733.46266537363, 53893.73053134709, 53894.0, 1412.3432603839265, 1453.989477313257, 1456.4584799524896, 1462.648964248594, 1463.0782606957762, 22100.88949555242, 22157.224497142524, 22225.77774222237, 22458.438539036, 22586.20948266803, 34720.652793471745, 37209.62790372062, 37234.25531489301, 37260.6273937268, 37276.62723372732, 193729.0, 195427.0, 196181.0, 196553.0, 196572.0, 31862.681373186897, 31940.0, 32328.83835580901, 35750.8212458936, 35756.4636530447, 44016.0, 44213.33679994867, 44268.778656106515, 44517.55482445134, 44591.33113003372, 31705.36589268248, 31852.0, 31886.681133188373, 32344.0, 32562.0, 31574.526382104745, 31659.20851978719, 31692.524612130375, 31808.840955795073, 31820.363592728478, 35996.8200159008, 36065.45901811423, 36184.63815361918, 36330.81834590916, 36416.0, 30805.0, 31218.531722023734, 31264.21839453941, 31385.901493447618, 31707.0, 23066.0, 23226.070957161523, 23354.0, 23473.41316467102, 23602.409939750956], "value": 17143.17217843756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [571.400029968533, 581.828360633613, 586.4723043681732, 591.2343515147884, 619.0806652121598, 21382.25162119326, 22365.217217397396, 22377.77622223777, 22379.44051398716, 22412.66381004282, 22736.65895011576, 23286.76713232867, 25044.74955250447, 25287.11495097672, 25323.493530129388, 6567.770128045531, 6798.660066996655, 6801.727930882758, 6802.625855577941, 6803.319668033207, 6403.967980160092, 6684.629650921574, 6778.389944904958, 6784.491163162757, 6799.660016999155, 6661.200655921296, 6663.2337281212795, 6666.3000384959605, 6667.766628168015, 6669.366410191048, 6651.201855777289, 6659.101021362127, 6659.966700166517, 6668.666566671647, 6670.566413183176, 5908.734106965197, 6113.174721412642, 6122.908156377658, 6190.3190649028575, 6198.721057552376, 29119.85440072786, 29404.852975735197, 29562.113136605938, 29694.851525742233, 29702.554461683092, 2054.578811343686, 2075.0973326602975, 2085.1450905128822, 2088.4361222469847, 2092.257248676728, 18956.24175032997, 19520.0, 19694.606107877906, 19781.60436791256, 19841.206351745914, 2464.7165575958816, 2478.083109249571, 2510.962335564968, 2530.2788705218923, 2531.3545046013332, 113629.0, 120169.0, 127441.0, 131003.0, 137083.31458342844, 14369.395358843769, 14672.266386680836, 14814.851851481348, 15027.364738223167, 15394.692106157814, 23158.305250842503, 23264.41838954006, 23277.41806454852, 23298.301050968497, 23366.0, 10226.181905447545, 10228.897711022943, 10325.380477171393, 10370.377777333233, 10404.375737455777, 9372.109649583279, 9389.295802814817, 9444.150026497511, 9449.574769135406, 9476.38901386766, 8894.243989260856, 9072.138146876168, 9171.678991235274, 9200.585973631069, 9404.811903762024, 2673.6791585009923, 2694.986525067362, 2715.4026114254834, 2748.945021099607, 2783.3737409082796, 961.1493827962164, 961.9178424272693, 970.7184916374173, 977.4819345746765, 990.8563258327646, 19044.0, 19124.0, 19153.712694309597, 19520.0, 19668.213271469267, 1026.9743256418649, 1030.8041472120274, 1052.9789404212027, 1053.1469509697017, 1074.0655629602295, 10683.465826708634, 10690.89309106899, 10754.0, 10921.617743378943, 11041.779164416666, 472671.0, 473649.0, 498229.0, 504904.0, 506468.0, 115991.42004290094, 119390.0, 119731.40134299273, 120219.39890300493, 121432.0, 1066.6586692258559, 1155.8150695888617, 1191.976160476803, 1196.3240768965507, 1208.2267348896712, 19869.403917882486, 20199.4950126248, 20297.594048119252, 20358.18567257293, 20555.280565180747, 54156.0, 54367.16579036735, 55081.449185509235, 55130.72434637801, 55498.16752748793, 67937.66031169813, 68679.31320686729, 68850.31149688439, 68915.0, 68946.65526672335, 14250.501232456814, 14554.0, 14603.561893143222, 14618.195999220145, 14631.195284259467, 22645.88677056604, 22754.886225568767, 22940.4264893379, 23087.0, 23102.306930792103, 18153.727694084864, 18532.351367702067, 18559.628807423505, 18561.071946402895, 18590.163442645386, 16082.919585401998, 16407.753883691992, 16413.589660258112, 16495.587610309838, 16796.24416901267, 17421.912890435466, 18188.818111818713, 18850.0, 18905.527361816065, 18924.810751892306, 14256.71486570242, 14333.713325733635, 14349.928250358682, 14365.497207597684, 14390.992630515764, 17930.28278868883, 18079.09604519795, 18245.908770456062, 18252.634947301245, 18321.0], "value": 11980.22176292915, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1369.643892587927, 1397.0709478197, 1420.55962651578, 1423.1887823940358, 1432.9856701432982, 12236.0, 12801.871981280172, 12827.42276597553, 14938.103713777155, 15318.46385376512, 14734.115953042825, 14965.775513367309, 14966.32651530681, 14988.700225995473, 15002.099874007567, 2138.796814302643, 2187.103287652063, 2191.999999999992, 2195.056125865877, 2196.769339219383, 2708.2281549758322, 2809.19937817722, 2812.086072026589, 2812.4656315300026, 2815.0569559197693, 2729.181245626314, 2736.2338545207276, 2754.490419272441, 2759.4756996170663, 2767.6540432445945, 2679.892804287836, 2695.17797071893, 2729.4541091781625, 2732.53546897027, 2751.2709132080013, 2632.3550730071065, 2647.444036752265, 2654.2302732207636, 2659.8670066496597, 2665.0539058634295, 14361.640958976004, 14538.709225815424, 14605.853941460555, 14671.193084380355, 14671.55985320442, 7632.771016869503, 7864.213578642147, 7867.9606601966625, 7873.173316801768, 7874.5668988205625, 5092.949070509285, 5169.327987361657, 5264.131418315973, 5319.946800531984, 5338.039152952467, 1788.5439213000732, 1790.4270633397189, 1805.1696219738935, 1833.51411875853, 1842.6314737052644, 24453.0, 24686.382840428952, 24697.753022469726, 24776.752232477804, 24803.25590232278, 6458.838529036811, 6534.085228068075, 6753.15585551806, 6818.147731533559, 6828.624425656537, 16107.0, 16123.435679751152, 16196.514104576881, 16308.184590770414, 16323.591910202102, 7414.703411863465, 7445.329920307198, 7616.543007419573, 7617.428692847948, 7623.504472209289, 6108.083787431832, 6220.906686399708, 7270.236625154388, 7281.890771638429, 7302.780916572511, 5219.582433405394, 5348.759305831247, 5383.2194331821665, 5431.456854314616, 5436.075867102602, 1464.875485583716, 1515.7423238049557, 1523.1622607565794, 1550.046721266413, 1566.0994927916493, 913.899471058183, 915.2220612479472, 926.0091701878924, 932.463833295844, 933.4819175357742, 7547.547147171187, 7565.43259255558, 7567.281108294703, 7578.242175782378, 7614.0, 721.4300702445009, 730.4302643937737, 730.6711979609197, 731.9634018299064, 742.6138408027904, 5174.715390653475, 5207.661502002357, 5324.201369794562, 5341.866453338697, 5347.0909945309395, 20043.59912801736, 20184.394468165978, 20578.27976020862, 20830.479238019172, 20996.895015524824, 20396.0, 20667.896660516602, 20737.0, 20754.06606702702, 20762.0, 10669.57321707123, 11232.719182020515, 11292.322460652387, 11303.717407064889, 11320.26418282809, 8150.429469937285, 8193.877091843508, 8220.753377398687, 8228.25945664893, 8319.334453243606, 16761.91619041897, 17365.56586085358, 17542.298308067533, 17591.032493212995, 17644.382446614305, 18022.0, 18138.274469021093, 18345.357912473, 18624.161912714233, 18680.252789888797, 9671.274654400808, 10595.523201456108, 10723.839142413026, 10827.296225745302, 10842.728931776453, 12103.878961210276, 13433.66415839612, 13577.524786632815, 13637.795433068313, 13670.31648417595, 10963.671089867317, 10985.395803230898, 10994.615188468366, 11033.393163376091, 11139.777204456028, 10779.784404312026, 10793.838092428463, 10954.849740777103, 11004.669859904216, 11261.436928153396, 9733.659321923982, 9780.951095244764, 9880.604775808888, 9892.703218903445, 9900.0, 7174.67713952863, 7197.532160409557, 7199.388052015428, 7224.638768061681, 7296.817579560341, 6876.449884009167, 6897.41371983387, 6962.094927659372, 6970.825729356807, 7038.788836334918], "value": 6420.962030259973, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.9261657901829, 521.9660722053069, 528.9308393323234, 537.5430883748811, 544.3413469701661, 6361.650109243989, 6388.48892088633, 6461.256955450113, 6464.127342808725, 6486.772962946297, 6264.968675156618, 6323.336049714782, 6326.968365158168, 6335.334789847069, 6368.586041907284, 5286.471352864702, 5296.179092240709, 5302.0, 5314.840554783353, 5368.141097424413, 4974.726390048546, 5110.540051395374, 5223.8432847014465, 5273.868153296179, 5286.207068939651, 5045.990801839628, 5081.2886195932415, 5081.8475445736685, 5107.744612769366, 5123.871903202431, 5019.247112933052, 5065.493450654942, 5067.923981140286, 5074.162763144077, 5103.2855400244, 4511.932321015187, 4588.0, 4589.24277494213, 4614.792334344963, 4626.0, 6185.226846644169, 6192.0, 6196.442320191204, 6215.595986260879, 6258.280297765769, 5654.660720356792, 5820.767169313222, 5828.795992140297, 5851.473367396955, 5883.79406720767, 6649.734010639569, 6672.633005184713, 6699.7456615049305, 6728.899066514006, 6768.916973284251, 4942.0, 5011.098002359574, 5026.47222041684, 5031.094403007457, 5047.520485553867, 6782.559133656297, 6785.355391237825, 6799.626020568867, 6862.382385585372, 6956.582605043714, 5098.0, 5103.260027296022, 5123.897522049539, 5127.6154288428515, 5149.639525233272, 5097.64316497849, 5138.203578445347, 5138.922916156261, 5150.201718733602, 5230.529252367305, 5733.426657334317, 5772.509336706343, 5825.475707186373, 5833.329167145833, 5842.2405087338375, 6702.865942681119, 6724.562903411263, 6727.192736871509, 6739.292374300726, 6798.082258895085, 1789.9552511187064, 1794.2015802967815, 1796.9820301797076, 1811.619559892411, 1812.546863284179, 1154.503563467717, 1228.846394200725, 1246.3269834289383, 1254.861965183829, 1264.121435602266, 5744.104445532964, 5745.626534275259, 5810.88378232433, 5824.796132135355, 5856.238688970407, 6643.236027856762, 6719.496037797185, 6732.865342693217, 6768.357006084414, 6776.69504872282, 5741.363711342247, 5750.712464376765, 5758.2514273144225, 5767.5674324425, 5785.537157027427, 3566.8777077638765, 3576.445650924111, 3607.909802254912, 3611.6930060944587, 3626.256617393428, 5907.763689452459, 6512.5766825156215, 6562.9326762069795, 6575.520507885783, 6618.139641846529, 6836.4530837532875, 6859.897101543481, 6877.6217308047535, 6889.175438070367, 7055.506114571931, 6450.903236451457, 6500.674966251669, 6518.120053792677, 6536.869262614816, 6537.248216455168, 6712.0, 6753.628550429774, 6801.89797153053, 6834.45324374039, 6846.383825455733, 6751.358620931003, 6810.284920083518, 6846.931530684828, 6863.897041544481, 6882.174139103339, 6568.784774816674, 6592.80221593353, 6595.835104122435, 6630.502712296694, 6638.800835974928, 5065.087574644521, 5081.974590127025, 5092.9814037193455, 5121.513456221653, 5143.765496280791, 6565.638889861103, 6588.769393071219, 6689.966550167218, 6708.295628958889, 6803.149606299213, 6578.309277525982, 6586.4401525870835, 6613.115262150264, 6623.6688165592495, 6628.370304821035, 6461.515386345949, 6545.934540654533, 6562.8359291018105, 6578.539502234989, 6619.569727967667, 6497.545171837926, 6539.874540774173, 6562.146920900444, 6579.407853293226, 6620.337966203341, 6616.7022483987375, 6658.6337751424135, 6662.467002639681, 6677.165354330708, 6728.158980127484, 6430.356964303533, 6470.0, 6473.385028422293, 6477.578957367756, 6483.546151769519], "value": 5140.672924758672, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [906.7461110888953, 917.2616044084511, 923.8845144356956, 931.585444477208, 938.0994245524296, 19617.0, 20506.2822801202, 20522.179112835507, 20524.28165014225, 20543.0, 18639.53401164968, 18723.251069957187, 18765.7185142223, 18780.342688005923, 18828.152733127008, 975.4391225045591, 975.6926568131051, 975.8536219567051, 975.9365641233333, 976.9658061967832, 979.7011911367031, 981.1562056631292, 981.6367943860781, 984.5569493727851, 986.5363279258737, 1002.6190047781832, 1019.6176433837311, 1031.3708637730979, 1032.0144262229553, 1032.2825636182856, 1022.2435397805644, 1023.81059503992, 1023.9948800255951, 1024.5748014573942, 1024.9128824049965, 736.4329466310911, 748.7416841189781, 749.3555542233674, 754.1478129713444, 760.8212070163507, 20421.8978905106, 20650.896745516176, 20671.689924650993, 20714.0, 20725.896370518203, 21117.0, 21233.575328493498, 21641.891790541104, 21840.563188736138, 21850.89074554633, 15675.059496430136, 15855.127967961758, 15880.523584292374, 16027.91986040074, 16458.670826583402, 3703.703703703697, 3710.591834898159, 3723.478712980185, 3725.9068523287133, 3734.1411475360546, 102740.48629756879, 105306.0, 140080.0, 142121.0, 145597.0, 17206.741898871525, 17483.0, 17573.560660983323, 17643.0, 17994.820051799314, 30918.381632367677, 31671.683283166873, 32291.0, 32299.51550726741, 32309.838450807594, 9091.227245684191, 9220.0, 9247.213986811197, 9258.537073146315, 9281.39670921388, 4112.383142528644, 4159.188958153191, 4183.974926143053, 4186.0, 4186.6859985501205, 5365.678059316376, 5403.5136837684795, 5431.131019036935, 5459.863503412367, 5471.562275017989, 1063.8723353197665, 1088.5264909764278, 1099.4228030284164, 1099.8350247462783, 1103.1450625765096, 678.575890068707, 687.2165731066588, 689.152342618572, 690.0959742737031, 691.6541729135432, 12288.754224915452, 12765.872341276654, 12891.140151061047, 13732.45070197201, 13845.376958036912, 681.7034589953386, 683.0505597219868, 689.0731965506386, 692.1210063219784, 705.9788206353818, 5562.360328562187, 5630.887382252332, 5645.491905728422, 5669.546436285087, 5760.337561180434, 127442.36278818733, 128923.0, 130211.0, 131951.0, 132366.0, 112016.43991779989, 112790.0, 113044.43477782559, 114104.42947785374, 114525.4273728626, 95854.52072739591, 97164.51417742866, 99486.5025674867, 99504.0, 100706.99293007122, 10299.588016479556, 10471.21465890046, 10539.789204216026, 10776.137908967106, 10839.295445795999, 41483.37774933318, 42183.156336873704, 42463.3630495549, 42781.35827962645, 43154.0, 55372.44627553673, 55449.44550554604, 56206.71896640491, 56367.43632563622, 57156.0, 15227.771583426036, 15507.069575825486, 15684.68630627404, 15702.528924132293, 15904.76142857835, 18157.45527634173, 18172.818271817643, 18229.54426139357, 18276.634467310847, 18426.81573184251, 16715.582110447336, 16810.831891680926, 16866.325346985985, 16879.07165105931, 16960.830391695927, 15789.0, 15871.920640396724, 15994.12032338186, 15997.0, 16025.0, 15666.138362390178, 15969.920150399174, 16026.198690065683, 16119.355225790838, 16185.514434566981, 11882.702932426759, 12411.317377544321, 12438.751224975269, 12520.874791252336, 12716.36418179106, 11161.944190278997, 11170.553177872795, 11276.774464510829, 11361.261518001304, 11458.484368203284], "value": 9028.531001351781, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1418.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 107.0, 107.0, 108.0, 109.0, 110.0, 108.0, 109.0, 109.0, 108.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 115.0, 117.0, 117.0, 123.0, 121.0, 120.0, 120.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 163.0, 162.0, 162.0, 163.0, 164.0, 163.0, 170.0, 168.0, 167.0], "value": 164.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 74.0, 74.0, 73.0, 74.0, 75.0, 73.0, 115.0], "value": 78.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 79.0, 79.0, 83.0, 79.0, 79.0, 79.0, 79.0, 80.0], "value": 79.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 117.0, 116.0, 117.0, 114.0, 114.0, 115.0, 114.0], "value": 114.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 149.0, 148.0, 147.0, 149.0, 151.0, 148.0, 148.0], "value": 148.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 93.0, 98.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 56.0, 53.0, 52.0, 55.0, 53.0, 53.0, 51.0, 52.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [41.0, 37.0, 37.0, 37.0, 36.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 37.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 72.0, 74.0, 73.0, 74.0, 73.0, 73.0, 73.0, 72.0, 73.0], "value": 73.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 110.0, 109.0, 114.0, 109.0, 114.0, 118.0, 118.0, 109.0], "value": 112.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 163.0, 160.0, 163.0, 168.0, 168.0, 173.0, 158.0, 168.0, 164.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 65.0, 67.0, 67.0, 66.0, 78.0, 65.0, 74.0, 68.0], "value": 68.3, "unit": "score"}]}]} 14:39:00 INFO - Return code: 0 14:39:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:39:00 INFO - # TBPL SUCCESS # 14:39:00 INFO - Running post-action listener: _package_coverage_data 14:39:00 INFO - Running post-action listener: _resource_record_post_action 14:39:00 INFO - [mozharness: 2017-10-03 21:39:00.428936Z] Finished run-tests step (success) 14:39:00 INFO - Running post-run listener: _resource_record_post_run 14:39:00 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 348057600; Read time: 12; Write time: 293216 14:39:00 INFO - TinderboxPrint: CPU usage
13.1% 14:39:00 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:39:00 INFO - TinderboxPrint: I/O write bytes / time
348,057,600 / 293,216 14:39:00 INFO - TinderboxPrint: CPU idle
8,227.6 (86.9%) 14:39:00 INFO - TinderboxPrint: CPU user
1,192.3 (12.6%) 14:39:00 INFO - TinderboxPrint: Swap in / out
0 / 0 14:39:00 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 12853248; Read time: 0; Write time: 1824 14:39:00 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:39:00 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 0; Write bytes: 334802944; Read time: 0; Write time: 291364 14:39:01 INFO - Running post-run listener: _upload_blobber_files 14:39:01 INFO - Blob upload gear active. 14:39:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:39:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:39:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:39:01 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:39:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:39:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:02 INFO - (blobuploader) - INFO - Done attempting. 14:39:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:39:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:39:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:03 INFO - (blobuploader) - INFO - Done attempting. 14:39:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:39:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:39:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:07 INFO - (blobuploader) - INFO - Done attempting. 14:39:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:39:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:39:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:08 INFO - (blobuploader) - INFO - Done attempting. 14:39:08 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:08 INFO - Return code: 0 14:39:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e3759b90ac874408127aabae75d00aea5ed6fc83d2da3f9c6375578cedd33b99e42c936305fe83c509eed63b99f62b73b73d85c38a17919c0a52afcac42183d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c267a5dea803e38f821241182e1b5c1b59373905ae655832169c3246384d92d8b0ab8fb6354cde126aff69f51637e24b7e36a24596509a3ef592f4401f716aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53f60b8b3a1d07c92365b054155a3e2d2422ada5af9234576106a9e8183a8a7a8b74d094a6725df7581228e672354a19014339771249cb4546d002e7b19a69f3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50754a1adb130540a437ede826a81ae5608cbd58d8da8722b2d3b7d302fe5c8a2efcda8cb131621e89285e41cb944df8e6087f1ab84afa5537b4c7a1d9ed5642"} 14:39:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:08 INFO - Contents: 14:39:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e3759b90ac874408127aabae75d00aea5ed6fc83d2da3f9c6375578cedd33b99e42c936305fe83c509eed63b99f62b73b73d85c38a17919c0a52afcac42183d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c267a5dea803e38f821241182e1b5c1b59373905ae655832169c3246384d92d8b0ab8fb6354cde126aff69f51637e24b7e36a24596509a3ef592f4401f716aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53f60b8b3a1d07c92365b054155a3e2d2422ada5af9234576106a9e8183a8a7a8b74d094a6725df7581228e672354a19014339771249cb4546d002e7b19a69f3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50754a1adb130540a437ede826a81ae5608cbd58d8da8722b2d3b7d302fe5c8a2efcda8cb131621e89285e41cb944df8e6087f1ab84afa5537b4c7a1d9ed5642"} 14:39:08 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:08 INFO - Copying logs to upload dir... 14:39:08 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1241.412344 ========= master_lag: 0.12 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 41 secs) (at 2017-10-03 14:39:08.061491) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:08.069387) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e3759b90ac874408127aabae75d00aea5ed6fc83d2da3f9c6375578cedd33b99e42c936305fe83c509eed63b99f62b73b73d85c38a17919c0a52afcac42183d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c267a5dea803e38f821241182e1b5c1b59373905ae655832169c3246384d92d8b0ab8fb6354cde126aff69f51637e24b7e36a24596509a3ef592f4401f716aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53f60b8b3a1d07c92365b054155a3e2d2422ada5af9234576106a9e8183a8a7a8b74d094a6725df7581228e672354a19014339771249cb4546d002e7b19a69f3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50754a1adb130540a437ede826a81ae5608cbd58d8da8722b2d3b7d302fe5c8a2efcda8cb131621e89285e41cb944df8e6087f1ab84afa5537b4c7a1d9ed5642"} build_url:https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011446 build_url: 'https://queue.taskcluster.net/v1/task/V7ZwxuDyRX-_fu7gAE5ohQ/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0e3759b90ac874408127aabae75d00aea5ed6fc83d2da3f9c6375578cedd33b99e42c936305fe83c509eed63b99f62b73b73d85c38a17919c0a52afcac42183d", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/8c267a5dea803e38f821241182e1b5c1b59373905ae655832169c3246384d92d8b0ab8fb6354cde126aff69f51637e24b7e36a24596509a3ef592f4401f716aa", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/53f60b8b3a1d07c92365b054155a3e2d2422ada5af9234576106a9e8183a8a7a8b74d094a6725df7581228e672354a19014339771249cb4546d002e7b19a69f3", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/50754a1adb130540a437ede826a81ae5608cbd58d8da8722b2d3b7d302fe5c8a2efcda8cb131621e89285e41cb944df8e6087f1ab84afa5537b4c7a1d9ed5642"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:08.103305) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:08.103624) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=fa44defc139c4ba8574fe040000001db-1507062357.619861-1306855757 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004466 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-10-03 14:39:08.184941) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 14:39:08.185272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-10-03 14:39:08.185591) ========= ========= Total master_lag: 0.38 =========