builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-035 starttime: 1484948494.17 results: success (0) buildid: 20170120120024 builduid: ed64c51883f74d0eabd7f6fa6bd0860e revision: 2d482631e0f14763a95ab18680d154cd267b775b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.169422) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.169840) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.170112) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.184826) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.185085) ========= 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=ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-20 13:41:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2017-01-20 13:41:34 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.088792 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.287825) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.288253) ========= 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=ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015312 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.337541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.338243) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d482631e0f14763a95ab18680d154cd267b775b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d482631e0f14763a95ab18680d154cd267b775b --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=ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-20 13:41:34,839 truncating revision to first 12 chars 2017-01-20 13:41:34,839 Setting DEBUG logging. 2017-01-20 13:41:34,839 attempt 1/10 2017-01-20 13:41:34,839 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2d482631e0f1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-20 13:41:35,147 unpacking tar archive at: mozilla-aurora-2d482631e0f1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.609903 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.962557) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.962882) ========= script_repo_revision: 2d482631e0f14763a95ab18680d154cd267b775b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.963282) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.963577) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-20 13:41:34.972010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-01-20 13:41:34.972276) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:41:35 INFO - MultiFileLogger online at 20170120 13:41:35 in /builds/slave/test 13:41:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:41:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:41:35 INFO - {'append_to_log': False, 13:41:35 INFO - 'base_work_dir': '/builds/slave/test', 13:41:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:41:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:41:35 INFO - 'branch': 'Mozilla-Aurora', 13:41:35 INFO - 'buildbot_json_path': 'buildprops.json', 13:41:35 INFO - 'config_files': ('talos/linux_config.py',), 13:41:35 INFO - 'default_actions': ('clobber', 13:41:35 INFO - 'read-buildbot-config', 13:41:35 INFO - 'download-and-extract', 13:41:35 INFO - 'populate-webroot', 13:41:35 INFO - 'create-virtualenv', 13:41:35 INFO - 'install', 13:41:35 INFO - 'run-tests'), 13:41:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:41:35 INFO - 'download_minidump_stackwalk': True, 13:41:35 INFO - 'download_symbols': 'ondemand', 13:41:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:41:35 INFO - 'tooltool.py': '/tools/tooltool.py', 13:41:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:41:35 INFO - '/tools/misc-python/virtualenv.py')}, 13:41:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:41:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:41:35 INFO - 'installer_path': 'installer.exe', 13:41:35 INFO - 'log_level': 'info', 13:41:35 INFO - 'log_name': 'talos', 13:41:35 INFO - 'log_to_console': True, 13:41:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:41:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:41:35 INFO - 'opt_config_files': (), 13:41:35 INFO - 'pip_index': False, 13:41:35 INFO - 'sps_profile': False, 13:41:35 INFO - 'sps_profile_interval': 0, 13:41:35 INFO - 'suite': 'dromaeojs', 13:41:35 INFO - 'system_bits': '32', 13:41:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:41:35 INFO - 'title': 'talos-linux64-ix-035', 13:41:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:41:35 INFO - 'use_talos_json': True, 13:41:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:41:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:41:35 INFO - 'work_dir': 'build'} 13:41:35 INFO - [mozharness: 2017-01-20 21:41:35.565478Z] Running clobber step. 13:41:35 INFO - Running pre-action listener: _resource_record_pre_action 13:41:35 INFO - Running main action method: clobber 13:41:35 INFO - rmtree: /builds/slave/test/build 13:41:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:41:36 INFO - Running post-action listener: _resource_record_post_action 13:41:36 INFO - [mozharness: 2017-01-20 21:41:36.027894Z] Finished clobber step (success) 13:41:36 INFO - [mozharness: 2017-01-20 21:41:36.027991Z] Running read-buildbot-config step. 13:41:36 INFO - Running pre-action listener: _resource_record_pre_action 13:41:36 INFO - Running main action method: read_buildbot_config 13:41:36 INFO - Using buildbot properties: 13:41:36 INFO - { 13:41:36 INFO - "project": "", 13:41:36 INFO - "product": "firefox", 13:41:36 INFO - "script_repo_revision": "production", 13:41:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:41:36 INFO - "repository": "", 13:41:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:41:36 INFO - "buildid": "20170120120024", 13:41:36 INFO - "pgo_build": "True", 13:41:36 INFO - "basedir": "/builds/slave/test", 13:41:36 INFO - "buildnumber": 1022, 13:41:36 INFO - "slavename": "talos-linux64-ix-035", 13:41:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:41:36 INFO - "platform": "ubuntu64_hw", 13:41:36 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:41:36 INFO - "branch": "mozilla-aurora", 13:41:36 INFO - "repo_path": "releases/mozilla-aurora", 13:41:36 INFO - "revision": "2d482631e0f14763a95ab18680d154cd267b775b", 13:41:36 INFO - "stage_platform": "linux64-pgo", 13:41:36 INFO - "builduid": "ed64c51883f74d0eabd7f6fa6bd0860e", 13:41:36 INFO - "slavebuilddir": "test" 13:41:36 INFO - } 13:41:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2. 13:41:36 INFO - Running post-action listener: _resource_record_post_action 13:41:36 INFO - [mozharness: 2017-01-20 21:41:36.030407Z] Finished read-buildbot-config step (success) 13:41:36 INFO - [mozharness: 2017-01-20 21:41:36.030495Z] Running download-and-extract step. 13:41:36 INFO - Running pre-action listener: _resource_record_pre_action 13:41:36 INFO - Running main action method: download_and_extract 13:41:36 INFO - mkdir: /builds/slave/test/build/tests 13:41:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:36 INFO - trying https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 13:41:36 INFO - Downloading https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 13:41:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:41:36 INFO - Downloaded 1361 bytes. 13:41:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.test_packages.json 13:41:36 INFO - Using the following test package requirements: 13:41:36 INFO - {u'common': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 13:41:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:41:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:41:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'jsshell-linux-x86_64.zip'], 13:41:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:41:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'], 13:41:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:41:36 INFO - u'talos': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:41:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:41:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', 13:41:36 INFO - u'firefox-52.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:41:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:41:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 13:41:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:41:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 13:41:37 INFO - Http code: 200 13:41:37 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 13:41:37 INFO - x-amz-cf-id: DixFP12ZOXA6wU1dmIGuRr-e6p_8RQTBhyWek6TDZUHDkcVHSfRrww== 13:41:37 INFO - x-amz-version-id: wDzAf.3vL7mVFyGuTahqIgLDoL1Pyse4 13:41:37 INFO - Expected file size: 21948690 13:41:37 INFO - Obtained file size: 21948690 13:41:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:41:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip 13:41:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:41:41 INFO - Fetch https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.common.tests.zip into memory 13:41:42 INFO - Http code: 200 13:41:42 INFO - via: 1.1 bfc29822e21ecc352f71dce83e06ab74.cloudfront.net (CloudFront) 13:41:42 INFO - x-amz-cf-id: vHw7VLgkjWF7qWpm7EOBEBtbKyMrmsEWkfjxxHa_ikWBQx9Too7Kxg== 13:41:42 INFO - x-amz-version-id: wDzAf.3vL7mVFyGuTahqIgLDoL1Pyse4 13:41:43 INFO - Expected file size: 21948690 13:41:43 INFO - Obtained file size: 21948690 13:41:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip 13:41:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:41:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.talos.tests.zip into memory 13:41:48 INFO - Http code: 200 13:41:48 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 13:41:48 INFO - x-amz-cf-id: Gw6WsMDwoCXt2k5ribfiYg9UgbOEex6m2ixU5WQxKOd-4H_ENAjGRA== 13:41:48 INFO - x-amz-version-id: WdorBuKVVvpoJWBYEcJp4.szeQh0I6D1 13:41:48 INFO - Expected file size: 14409038 13:41:48 INFO - Obtained file size: 14409038 13:41:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:48 INFO - trying https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 13:41:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 13:41:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:41:49 INFO - Downloaded 64202034 bytes. 13:41:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 13:41:49 INFO - mkdir: /builds/slave/test/properties 13:41:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:41:49 INFO - Writing to file /builds/slave/test/properties/build_url 13:41:49 INFO - Contents: 13:41:49 INFO - build_url:https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 13:41:50 INFO - Running post-action listener: _resource_record_post_action 13:41:50 INFO - Running post-action listener: set_extra_try_arguments 13:41:50 INFO - [mozharness: 2017-01-20 21:41:50.292250Z] Finished download-and-extract step (success) 13:41:50 INFO - [mozharness: 2017-01-20 21:41:50.292477Z] Running populate-webroot step. 13:41:50 INFO - Running pre-action listener: _resource_record_pre_action 13:41:50 INFO - Running main action method: populate_webroot 13:41:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:41:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:41:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:41:50 INFO - '--tppagecycles', 13:41:50 INFO - '7'], 13:41:50 INFO - 'tests': ['tsvgm']}}, 13:41:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:41:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:41:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:41:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:41:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:41:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:41:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['damp', 'tps']}, 13:41:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'tests': ['damp', 'tps']}, 13:41:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['dromaeo_dom']}, 13:41:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:41:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['basic_compositor_video']}, 13:41:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:41:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['a11yr', 13:41:50 INFO - 'ts_paint', 13:41:50 INFO - 'tpaint', 13:41:50 INFO - 'sessionrestore', 13:41:50 INFO - 'sessionrestore_no_auto_restore', 13:41:50 INFO - 'tabpaint']}, 13:41:50 INFO - 'other-e10s': {'tests': ['a11yr', 13:41:50 INFO - 'ts_paint', 13:41:50 INFO - 'tpaint', 13:41:50 INFO - 'sessionrestore', 13:41:50 INFO - 'sessionrestore_no_auto_restore', 13:41:50 INFO - 'tabpaint']}, 13:41:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:41:50 INFO - 'ts_paint', 13:41:50 INFO - 'tpaint', 13:41:50 INFO - 'sessionrestore', 13:41:50 INFO - 'sessionrestore_no_auto_restore', 13:41:50 INFO - 'tabpaint']}, 13:41:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:41:50 INFO - 'ts_paint', 13:41:50 INFO - 'tpaint', 13:41:50 INFO - 'sessionrestore', 13:41:50 INFO - 'sessionrestore_no_auto_restore', 13:41:50 INFO - 'tabpaint']}, 13:41:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['a11yr', 13:41:50 INFO - 'ts_paint', 13:41:50 INFO - 'tpaint', 13:41:50 INFO - 'sessionrestore', 13:41:50 INFO - 'sessionrestore_no_auto_restore', 13:41:50 INFO - 'tabpaint']}, 13:41:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['a11yr', 13:41:50 INFO - 'ts_paint', 13:41:50 INFO - 'tpaint', 13:41:50 INFO - 'sessionrestore', 13:41:50 INFO - 'sessionrestore_no_auto_restore', 13:41:50 INFO - 'tabpaint']}, 13:41:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['tsvgx', 13:41:50 INFO - 'tsvgr_opacity', 13:41:50 INFO - 'tart', 13:41:50 INFO - 'tscrollx', 13:41:50 INFO - 'cart']}, 13:41:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:41:50 INFO - 'tsvgr_opacity', 13:41:50 INFO - 'tart', 13:41:50 INFO - 'tscrollx', 13:41:50 INFO - 'cart']}, 13:41:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'talos_options': ['--disable-e10s'], 13:41:50 INFO - 'tests': ['tp5o']}, 13:41:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'tests': ['tp5o']}, 13:41:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'talos_options': ['--disable-e10s', 13:41:50 INFO - '--xperf_path', 13:41:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:41:50 INFO - 'tests': ['tp5n']}, 13:41:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:41:50 INFO - 'talos_options': ['--xperf_path', 13:41:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:41:50 INFO - 'tests': ['tp5n']}}, 13:41:50 INFO - 'talos.zip': {'path': '', 13:41:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:41:50 INFO - Running post-action listener: _resource_record_post_action 13:41:50 INFO - [mozharness: 2017-01-20 21:41:50.308883Z] Finished populate-webroot step (success) 13:41:50 INFO - [mozharness: 2017-01-20 21:41:50.308970Z] Running create-virtualenv step. 13:41:50 INFO - Running pre-action listener: _resource_record_pre_action 13:41:50 INFO - Running main action method: create_virtualenv 13:41:50 INFO - Creating virtualenv /builds/slave/test/build/venv 13:41:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:41:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:41:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:41:50 INFO - Using real prefix '/usr' 13:41:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:41:50 INFO - Installing distribute.............................................................................................................................................................................................done. 13:41:52 INFO - Installing pip.................done. 13:41:52 INFO - Return code: 0 13:41:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:41:53 INFO - Reading from file tmpfile_stdout 13:41:53 INFO - Output received: 13:41:53 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:41:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:41:53 INFO - Using env: {'DISPLAY': ':0', 13:41:53 INFO - 'HOME': '/home/cltbld', 13:41:53 INFO - 'LANG': 'en_US.UTF-8', 13:41:53 INFO - 'LANGUAGE': 'en_US:en', 13:41:53 INFO - 'LOGNAME': 'cltbld', 13:41:53 INFO - 'MAIL': '/var/mail/cltbld', 13:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:53 INFO - 'MOZ_NO_REMOTE': '1', 13:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:53 INFO - 'NO_EM_RESTART': '1', 13:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:53 INFO - 'PWD': '/builds/slave/test', 13:41:53 INFO - 'SHELL': '/bin/bash', 13:41:53 INFO - 'SHLVL': '1', 13:41:53 INFO - 'TERM': 'linux', 13:41:53 INFO - 'TMOUT': '86400', 13:41:53 INFO - 'USER': 'cltbld', 13:41:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:53 INFO - '_': '/tools/buildbot/bin/python'} 13:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:53 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:41:53 INFO - Cleaning up... 13:41:53 INFO - Return code: 0 13:41:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:41:53 INFO - Using env: {'DISPLAY': ':0', 13:41:53 INFO - 'HOME': '/home/cltbld', 13:41:53 INFO - 'LANG': 'en_US.UTF-8', 13:41:53 INFO - 'LANGUAGE': 'en_US:en', 13:41:53 INFO - 'LOGNAME': 'cltbld', 13:41:53 INFO - 'MAIL': '/var/mail/cltbld', 13:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:53 INFO - 'MOZ_NO_REMOTE': '1', 13:41:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:53 INFO - 'NO_EM_RESTART': '1', 13:41:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:53 INFO - 'PWD': '/builds/slave/test', 13:41:53 INFO - 'SHELL': '/bin/bash', 13:41:53 INFO - 'SHLVL': '1', 13:41:53 INFO - 'TERM': 'linux', 13:41:53 INFO - 'TMOUT': '86400', 13:41:53 INFO - 'USER': 'cltbld', 13:41:53 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:53 INFO - '_': '/tools/buildbot/bin/python'} 13:41:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:53 INFO - Downloading/unpacking psutil>=3.1.1 13:41: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 13:41: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 13:41:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41: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 13:41: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 13:41:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:41:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:41:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:41:55 INFO - Installing collected packages: psutil 13:41:55 INFO - Running setup.py install for psutil 13:41:55 INFO - building 'psutil._psutil_linux' extension 13:41: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 13:41: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 13:41:55 INFO - building 'psutil._psutil_posix' extension 13:41: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 13:41:56 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 13:41:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:41:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:41:56 INFO - Successfully installed psutil 13:41:56 INFO - Cleaning up... 13:41:56 INFO - Return code: 0 13:41:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:41:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:41:56 INFO - Using env: {'DISPLAY': ':0', 13:41:56 INFO - 'HOME': '/home/cltbld', 13:41:56 INFO - 'LANG': 'en_US.UTF-8', 13:41:56 INFO - 'LANGUAGE': 'en_US:en', 13:41:56 INFO - 'LOGNAME': 'cltbld', 13:41:56 INFO - 'MAIL': '/var/mail/cltbld', 13:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:56 INFO - 'MOZ_NO_REMOTE': '1', 13:41:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:56 INFO - 'NO_EM_RESTART': '1', 13:41:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:56 INFO - 'PWD': '/builds/slave/test', 13:41:56 INFO - 'SHELL': '/bin/bash', 13:41:56 INFO - 'SHLVL': '1', 13:41:56 INFO - 'TERM': 'linux', 13:41:56 INFO - 'TMOUT': '86400', 13:41:56 INFO - 'USER': 'cltbld', 13:41:56 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:56 INFO - '_': '/tools/buildbot/bin/python'} 13:41:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:41: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 13:41: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 13:41:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41: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 13:41: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 13:41:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:41:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:41:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:41:58 INFO - Installing collected packages: mozsystemmonitor 13:41:58 INFO - Running setup.py install for mozsystemmonitor 13:41:58 INFO - Successfully installed mozsystemmonitor 13:41:58 INFO - Cleaning up... 13:41:58 INFO - Return code: 0 13:41:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:41:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:41:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:41: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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:41:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:41:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:41:58 INFO - Using env: {'DISPLAY': ':0', 13:41:58 INFO - 'HOME': '/home/cltbld', 13:41:58 INFO - 'LANG': 'en_US.UTF-8', 13:41:58 INFO - 'LANGUAGE': 'en_US:en', 13:41:58 INFO - 'LOGNAME': 'cltbld', 13:41:58 INFO - 'MAIL': '/var/mail/cltbld', 13:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:41:58 INFO - 'MOZ_NO_REMOTE': '1', 13:41:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:41:58 INFO - 'NO_EM_RESTART': '1', 13:41:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:41:58 INFO - 'PWD': '/builds/slave/test', 13:41:58 INFO - 'SHELL': '/bin/bash', 13:41:58 INFO - 'SHLVL': '1', 13:41:58 INFO - 'TERM': 'linux', 13:41:58 INFO - 'TMOUT': '86400', 13:41:58 INFO - 'USER': 'cltbld', 13:41:58 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:41:58 INFO - '_': '/tools/buildbot/bin/python'} 13:41:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:41:58 INFO - Downloading/unpacking jsonschema==2.5.1 13:41: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 13:41: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 13:41:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:41: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 13:41: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 13:42:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:42:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:42: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 13:42: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 13:42:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42: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 13:42: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 13:42:01 INFO - Downloading functools32-3.2.3-2.tar.gz 13:42:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:42:01 INFO - warning: no files found matching '*.txt' 13:42:01 INFO - no previously-included directories found matching 'build' 13:42:01 INFO - no previously-included directories found matching 'dist' 13:42:01 INFO - no previously-included directories found matching '.git*' 13:42:01 INFO - Installing collected packages: jsonschema, functools32 13:42:01 INFO - Running setup.py install for functools32 13:42:01 INFO - warning: no files found matching '*.txt' 13:42:01 INFO - no previously-included directories found matching 'build' 13:42:01 INFO - no previously-included directories found matching 'dist' 13:42:01 INFO - no previously-included directories found matching '.git*' 13:42:01 INFO - Successfully installed jsonschema functools32 13:42:01 INFO - Cleaning up... 13:42:01 INFO - Return code: 0 13:42:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:42:01 INFO - Using env: {'DISPLAY': ':0', 13:42:01 INFO - 'HOME': '/home/cltbld', 13:42:01 INFO - 'LANG': 'en_US.UTF-8', 13:42:01 INFO - 'LANGUAGE': 'en_US:en', 13:42:01 INFO - 'LOGNAME': 'cltbld', 13:42:01 INFO - 'MAIL': '/var/mail/cltbld', 13:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:01 INFO - 'MOZ_NO_REMOTE': '1', 13:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:01 INFO - 'NO_EM_RESTART': '1', 13:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:01 INFO - 'PWD': '/builds/slave/test', 13:42:01 INFO - 'SHELL': '/bin/bash', 13:42:01 INFO - 'SHLVL': '1', 13:42:01 INFO - 'TERM': 'linux', 13:42:01 INFO - 'TMOUT': '86400', 13:42:01 INFO - 'USER': 'cltbld', 13:42:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:01 INFO - '_': '/tools/buildbot/bin/python'} 13:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:42:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:42:01 INFO - Cleaning up... 13:42:01 INFO - Return code: 0 13:42:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:42:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:42:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:01 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:42:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:42:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:42:01 INFO - Using env: {'DISPLAY': ':0', 13:42:01 INFO - 'HOME': '/home/cltbld', 13:42:01 INFO - 'LANG': 'en_US.UTF-8', 13:42:01 INFO - 'LANGUAGE': 'en_US:en', 13:42:01 INFO - 'LOGNAME': 'cltbld', 13:42:01 INFO - 'MAIL': '/var/mail/cltbld', 13:42:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:01 INFO - 'MOZ_NO_REMOTE': '1', 13:42:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:01 INFO - 'NO_EM_RESTART': '1', 13:42:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:01 INFO - 'PWD': '/builds/slave/test', 13:42:01 INFO - 'SHELL': '/bin/bash', 13:42:01 INFO - 'SHLVL': '1', 13:42:01 INFO - 'TERM': 'linux', 13:42:01 INFO - 'TMOUT': '86400', 13:42:01 INFO - 'USER': 'cltbld', 13:42:01 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:01 INFO - '_': '/tools/buildbot/bin/python'} 13:42:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:42:01 INFO - Downloading/unpacking blobuploader==1.2.4 13:42: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 13:42: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 13:42:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42: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 13:42: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 13:42:03 INFO - Downloading blobuploader-1.2.4.tar.gz 13:42:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:42:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:42:03 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 13:42:03 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 13:42:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:03 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 13:42:03 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 13:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:42:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:42:04 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 13:42:04 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 13:42:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:04 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 13:42:04 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 13:42:04 INFO - Downloading docopt-0.6.1.tar.gz 13:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:42:04 INFO - Installing collected packages: blobuploader, requests, docopt 13:42:04 INFO - Running setup.py install for blobuploader 13:42:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:42:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:42:04 INFO - Running setup.py install for requests 13:42:05 INFO - Running setup.py install for docopt 13:42:05 INFO - Successfully installed blobuploader requests docopt 13:42:05 INFO - Cleaning up... 13:42:05 INFO - Return code: 0 13:42:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:42:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:42:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:42:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:42:05 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:42:05 INFO - Using env: {'DISPLAY': ':0', 13:42:05 INFO - 'HOME': '/home/cltbld', 13:42:05 INFO - 'LANG': 'en_US.UTF-8', 13:42:05 INFO - 'LANGUAGE': 'en_US:en', 13:42:05 INFO - 'LOGNAME': 'cltbld', 13:42:05 INFO - 'MAIL': '/var/mail/cltbld', 13:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:05 INFO - 'MOZ_NO_REMOTE': '1', 13:42:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:05 INFO - 'NO_EM_RESTART': '1', 13:42:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:05 INFO - 'PWD': '/builds/slave/test', 13:42:05 INFO - 'SHELL': '/bin/bash', 13:42:05 INFO - 'SHLVL': '1', 13:42:05 INFO - 'TERM': 'linux', 13:42:05 INFO - 'TMOUT': '86400', 13:42:05 INFO - 'USER': 'cltbld', 13:42:05 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:05 INFO - '_': '/tools/buildbot/bin/python'} 13:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:42:05 INFO - Running setup.py (path:/tmp/pip-XEJWur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:42:05 INFO - Running setup.py (path:/tmp/pip-DVb736-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:42:05 INFO - Running setup.py (path:/tmp/pip-OLi2Pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:42:05 INFO - Running setup.py (path:/tmp/pip-UB7WvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:42:05 INFO - Running setup.py (path:/tmp/pip-KTFQCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:42:05 INFO - Running setup.py (path:/tmp/pip-H1Vesv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:42:05 INFO - Running setup.py (path:/tmp/pip-tQBix1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:42:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:42:05 INFO - Running setup.py (path:/tmp/pip-3_NTqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:42:06 INFO - Running setup.py (path:/tmp/pip-lFEO9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:42:06 INFO - Running setup.py (path:/tmp/pip-ZXbaWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:42:06 INFO - Running setup.py (path:/tmp/pip-3NyzGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:42:06 INFO - Running setup.py (path:/tmp/pip-ZOIEz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:42:06 INFO - Running setup.py (path:/tmp/pip-yO8Np0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:42:06 INFO - Running setup.py (path:/tmp/pip-bjFmIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:42:06 INFO - Running setup.py (path:/tmp/pip-85dIXn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:42:06 INFO - Running setup.py (path:/tmp/pip-2zgBqg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:42:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:42:06 INFO - Running setup.py (path:/tmp/pip-5KsED5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:42:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:42:06 INFO - Running setup.py install for manifestparser 13:42:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:42:06 INFO - Running setup.py install for mozcrash 13:42:06 INFO - Running setup.py install for mozdebug 13:42:06 INFO - Running setup.py install for mozdevice 13:42:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Running setup.py install for mozfile 13:42:07 INFO - Running setup.py install for mozhttpd 13:42:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Running setup.py install for mozinfo 13:42:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Running setup.py install for mozInstall 13:42:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Running setup.py install for mozleak 13:42:07 INFO - Running setup.py install for mozlog 13:42:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Running setup.py install for moznetwork 13:42:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:42:07 INFO - Running setup.py install for mozprocess 13:42:07 INFO - Running setup.py install for mozprofile 13:42:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:42:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:42:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:42:08 INFO - Running setup.py install for mozrunner 13:42:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:42:08 INFO - Running setup.py install for mozscreenshot 13:42:08 INFO - Running setup.py install for moztest 13:42:08 INFO - Running setup.py install for mozversion 13:42:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:42:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:42:08 INFO - Cleaning up... 13:42:08 INFO - Return code: 0 13:42:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:42:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:42:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:42:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:42:08 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:42:08 INFO - Using env: {'DISPLAY': ':0', 13:42:08 INFO - 'HOME': '/home/cltbld', 13:42:08 INFO - 'LANG': 'en_US.UTF-8', 13:42:08 INFO - 'LANGUAGE': 'en_US:en', 13:42:08 INFO - 'LOGNAME': 'cltbld', 13:42:08 INFO - 'MAIL': '/var/mail/cltbld', 13:42:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:08 INFO - 'MOZ_NO_REMOTE': '1', 13:42:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:08 INFO - 'NO_EM_RESTART': '1', 13:42:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:08 INFO - 'PWD': '/builds/slave/test', 13:42:08 INFO - 'SHELL': '/bin/bash', 13:42:08 INFO - 'SHLVL': '1', 13:42:08 INFO - 'TERM': 'linux', 13:42:08 INFO - 'TMOUT': '86400', 13:42:08 INFO - 'USER': 'cltbld', 13:42:08 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:08 INFO - '_': '/tools/buildbot/bin/python'} 13:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:42:08 INFO - Running setup.py (path:/tmp/pip-Q7UC32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:42:08 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)) 13:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:42:08 INFO - Running setup.py (path:/tmp/pip-q7M3Ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:42:08 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)) 13:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:42:08 INFO - Running setup.py (path:/tmp/pip-ceMUrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:42:08 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)) 13:42:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:42:08 INFO - Running setup.py (path:/tmp/pip-qIY4mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:42:09 INFO - Running setup.py (path:/tmp/pip-MIGoiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:42:09 INFO - Running setup.py (path:/tmp/pip-aLxE0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:42:09 INFO - Running setup.py (path:/tmp/pip-U4DHRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:42:09 INFO - Running setup.py (path:/tmp/pip-MAYBER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:42:09 INFO - Running setup.py (path:/tmp/pip-jZgy3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:42:09 INFO - Running setup.py (path:/tmp/pip-7WgajD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:42:09 INFO - Running setup.py (path:/tmp/pip-lJyU9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:42:09 INFO - Running setup.py (path:/tmp/pip-UPmOsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:42:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:42:09 INFO - Running setup.py (path:/tmp/pip-sjaBO6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:42:09 INFO - Running setup.py (path:/tmp/pip-oStWjg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:42:09 INFO - Running setup.py (path:/tmp/pip-VGAdIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:42:09 INFO - Running setup.py (path:/tmp/pip-8sVSvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:42:09 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)) 13:42:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:42:09 INFO - Running setup.py (path:/tmp/pip-NvMlbQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:42:09 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)) 13:42:09 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)) 13:42:09 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)) 13:42:09 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)) 13:42:09 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:42:09 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:42:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:42: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 13:42: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 13:42:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:42: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 13:42: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 13:42:11 INFO - Downloading blessings-1.6.tar.gz 13:42:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:42:12 INFO - Installing collected packages: blessings 13:42:12 INFO - Running setup.py install for blessings 13:42:12 INFO - Successfully installed blessings 13:42:12 INFO - Cleaning up... 13:42:12 INFO - Return code: 0 13:42:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:42:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:42:12 INFO - Reading from file tmpfile_stdout 13:42:12 INFO - Current package versions: 13:42:12 INFO - argparse == 1.2.1 13:42:12 INFO - blessings == 1.6 13:42:12 INFO - blobuploader == 1.2.4 13:42:12 INFO - docopt == 0.6.1 13:42:12 INFO - functools32 == 3.2.3-2 13:42:12 INFO - jsonschema == 2.5.1 13:42:12 INFO - manifestparser == 1.1 13:42:12 INFO - mozInstall == 1.12 13:42:12 INFO - mozcrash == 1.0 13:42:12 INFO - mozdebug == 0.1 13:42:12 INFO - mozdevice == 0.48 13:42:12 INFO - mozfile == 1.2 13:42:12 INFO - mozhttpd == 0.7 13:42:12 INFO - mozinfo == 0.9 13:42:12 INFO - mozleak == 0.1 13:42:12 INFO - mozlog == 3.4 13:42:12 INFO - moznetwork == 0.27 13:42:12 INFO - mozprocess == 0.23 13:42:12 INFO - mozprofile == 0.28 13:42:12 INFO - mozrunner == 6.13 13:42:12 INFO - mozscreenshot == 0.1 13:42:12 INFO - mozsystemmonitor == 0.3 13:42:12 INFO - moztest == 0.8 13:42:12 INFO - mozversion == 1.4 13:42:12 INFO - psutil == 3.1.1 13:42:12 INFO - requests == 1.2.3 13:42:12 INFO - wsgiref == 0.1.2 13:42:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:42:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 13:42:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:42:12 INFO - Using env: {'DISPLAY': ':0', 13:42:12 INFO - 'HOME': '/home/cltbld', 13:42:12 INFO - 'LANG': 'en_US.UTF-8', 13:42:12 INFO - 'LANGUAGE': 'en_US:en', 13:42:12 INFO - 'LOGNAME': 'cltbld', 13:42:12 INFO - 'MAIL': '/var/mail/cltbld', 13:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:12 INFO - 'MOZ_NO_REMOTE': '1', 13:42:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:12 INFO - 'NO_EM_RESTART': '1', 13:42:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:12 INFO - 'PWD': '/builds/slave/test', 13:42:12 INFO - 'SHELL': '/bin/bash', 13:42:12 INFO - 'SHLVL': '1', 13:42:12 INFO - 'TERM': 'linux', 13:42:12 INFO - 'TMOUT': '86400', 13:42:12 INFO - 'USER': 'cltbld', 13:42:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:12 INFO - '_': '/tools/buildbot/bin/python'} 13:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 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)) 13:42:12 INFO - Cleaning up... 13:42:12 INFO - Return code: 0 13:42:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:42:12 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7fb84b789f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x14858a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1552ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, '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': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', '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 13:42:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:42:12 INFO - Using env: {'DISPLAY': ':0', 13:42:12 INFO - 'HOME': '/home/cltbld', 13:42:12 INFO - 'LANG': 'en_US.UTF-8', 13:42:12 INFO - 'LANGUAGE': 'en_US:en', 13:42:12 INFO - 'LOGNAME': 'cltbld', 13:42:12 INFO - 'MAIL': '/var/mail/cltbld', 13:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:12 INFO - 'MOZ_NO_REMOTE': '1', 13:42:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:12 INFO - 'NO_EM_RESTART': '1', 13:42:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:12 INFO - 'PWD': '/builds/slave/test', 13:42:12 INFO - 'SHELL': '/bin/bash', 13:42:12 INFO - 'SHLVL': '1', 13:42:12 INFO - 'TERM': 'linux', 13:42:12 INFO - 'TMOUT': '86400', 13:42:12 INFO - 'USER': 'cltbld', 13:42:12 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:12 INFO - '_': '/tools/buildbot/bin/python'} 13:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:42:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:42:12 INFO - Cleaning up... 13:42:12 INFO - Return code: 0 13:42:12 INFO - Running post-action listener: _resource_record_post_action 13:42:12 INFO - Running post-action listener: _start_resource_monitoring 13:42:12 INFO - Starting resource monitoring. 13:42:12 INFO - [mozharness: 2017-01-20 21:42:12.935542Z] Finished create-virtualenv step (success) 13:42:12 INFO - [mozharness: 2017-01-20 21:42:12.936045Z] Running install step. 13:42:12 INFO - Running pre-action listener: _resource_record_pre_action 13:42:12 INFO - Running main action method: install 13:42:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:42:13 INFO - Reading from file tmpfile_stdout 13:42:13 INFO - Detecting whether we're running mozinstall >=1.0... 13:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:42:13 INFO - Reading from file tmpfile_stdout 13:42:13 INFO - Output received: 13:42:13 INFO - Usage: mozinstall [options] installer 13:42:13 INFO - Options: 13:42:13 INFO - -h, --help show this help message and exit 13:42:13 INFO - -d DEST, --destination=DEST 13:42:13 INFO - Directory to install application into. [default: 13:42:13 INFO - "/builds/slave/test"] 13:42:13 INFO - --app=APP Application being installed. [default: firefox] 13:42:13 INFO - mkdir: /builds/slave/test/build/application 13:42:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:42:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:42:29 INFO - Reading from file tmpfile_stdout 13:42:29 INFO - Output received: 13:42:29 INFO - /builds/slave/test/build/application/firefox/firefox 13:42:29 INFO - Running post-action listener: _resource_record_post_action 13:42:29 INFO - [mozharness: 2017-01-20 21:42:29.186643Z] Finished install step (success) 13:42:29 INFO - [mozharness: 2017-01-20 21:42:29.186885Z] Running run-tests step. 13:42:29 INFO - Running pre-action listener: _resource_record_pre_action 13:42:29 INFO - Running main action method: run_tests 13:42:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:42:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:42:29 INFO - Python 2.7.3 13:42:29 INFO - Return code: 0 13:42:29 INFO - grabbing minidump binary from tooltool 13:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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')]} 13:42:29 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x154ff60>, '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 0x1550d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15511d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:42:29 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 13:42:29 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 13:42:29 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 13:42:29 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:42:29 INFO - Return code: 0 13:42:29 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:42:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:42:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:42:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:42:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:42:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:42:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-035 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:42:29 INFO - Using env: {'DISPLAY': ':0', 13:42:29 INFO - 'HOME': '/home/cltbld', 13:42:29 INFO - 'LANG': 'en_US.UTF-8', 13:42:29 INFO - 'LANGUAGE': 'en_US:en', 13:42:29 INFO - 'LOGNAME': 'cltbld', 13:42:29 INFO - 'MAIL': '/var/mail/cltbld', 13:42:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:42:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:42:29 INFO - 'MOZ_NO_REMOTE': '1', 13:42:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:42:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:42:29 INFO - 'NO_EM_RESTART': '1', 13:42:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:42:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:42:29 INFO - 'PWD': '/builds/slave/test', 13:42:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:42:29 INFO - 'SHELL': '/bin/bash', 13:42:29 INFO - 'SHLVL': '1', 13:42:29 INFO - 'TERM': 'linux', 13:42:29 INFO - 'TMOUT': '86400', 13:42:29 INFO - 'USER': 'cltbld', 13:42:29 INFO - 'XDG_SESSION_COOKIE': 'ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478', 13:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:42:29 INFO - '_': '/tools/buildbot/bin/python'} 13:42:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-035', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:42:29 INFO - mozversion application_buildid: 20170120120024 13:42:29 INFO - mozversion application_changeset: 2d482631e0f14763a95ab18680d154cd267b775b 13:42:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:42:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:42:29 INFO - mozversion application_name: Firefox 13:42:29 INFO - mozversion application_remotingname: firefox-dev 13:42:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:42:29 INFO - mozversion application_vendor: Mozilla 13:42:29 INFO - mozversion application_version: 52.0a2 13:42:29 INFO - mozversion platform_buildid: 20170120120024 13:42:29 INFO - mozversion platform_changeset: 2d482631e0f14763a95ab18680d154cd267b775b 13:42:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:42:29 INFO - mozversion platform_version: 52.0a2 13:42:29 INFO - using testdate: 1484948549 13:42:29 INFO - actual date: 1484948549 13:42:29 INFO - starting webserver on 'localhost:47990' 13:42:29 INFO - SUITE-START | Running 2 tests 13:42:29 INFO - TEST-START | dromaeo_css 13:42:29 INFO - operating with platform_type : linux_ 13:42:29 INFO - Initialising browser for dromaeo_css test... 13:42:29 INFO - TEST-INFO | started process 4836 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8K7Wi4/profile http://localhost:47990/getInfo.html) 13:42:33 INFO - PROCESS | 4836 | __metrics Screen width/height:1600/1200 13:42:33 INFO - PROCESS | 4836 | colorDepth:24 13:42:33 INFO - PROCESS | 4836 | Browser inner width/height: 1024/697 13:42:33 INFO - PROCESS | 4836 | __metrics 13:42:33 INFO - PROCESS | 4836 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:42:33 INFO - PROCESS | 4836 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:42:34 INFO - PROCESS | 4836 | console.error: PushService: 13:42:34 INFO - PROCESS | 4836 | stateChangeProcessEnqueue: Error transitioning state 13:42:34 INFO - PROCESS | 4836 | UnknownError 13:42:34 INFO - TEST-INFO | 4836: exit 0 13:42:34 INFO - Browser initialized. 13:42:34 INFO - Running cycle 1/1 for dromaeo_css test... 13:42:34 INFO - TEST-INFO | started process 4926 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8K7Wi4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:42:46 INFO - PROCESS | 4926 | RSS: Main: 143835136 13:42:46 INFO - PROCESS | 4926 | 13:45:48 INFO - PROCESS | 4926 | Cycle 1(1): loaded http://localhost:47990/tests/dromaeo/cssquery-dojo.html (next: http://localhost:47990/tests/dromaeo/cssquery-ext.html) 13:45:49 INFO - PROCESS | 4926 | RSS: Main: 243146752 13:45:49 INFO - PROCESS | 4926 | 13:48:51 INFO - PROCESS | 4926 | Cycle 1(1): loaded http://localhost:47990/tests/dromaeo/cssquery-ext.html (next: http://localhost:47990/tests/dromaeo/cssquery-jquery.html) 13:48:52 INFO - PROCESS | 4926 | RSS: Main: 233123840 13:48:52 INFO - PROCESS | 4926 | 13:51:54 INFO - PROCESS | 4926 | Cycle 1(1): loaded http://localhost:47990/tests/dromaeo/cssquery-jquery.html (next: http://localhost:47990/tests/dromaeo/cssquery-mootools.html) 13:51:54 INFO - PROCESS | 4926 | RSS: Main: 176099328 13:51:54 INFO - PROCESS | 4926 | 13:54:56 INFO - PROCESS | 4926 | Cycle 1(1): loaded http://localhost:47990/tests/dromaeo/cssquery-mootools.html (next: http://localhost:47990/tests/dromaeo/cssquery-prototype.html) 13:54:57 INFO - PROCESS | 4926 | RSS: Main: 175435776 13:54:57 INFO - PROCESS | 4926 | 13:58:00 INFO - PROCESS | 4926 | Cycle 1(1): loaded http://localhost:47990/tests/dromaeo/cssquery-prototype.html (next: http://localhost:47990/tests/dromaeo/cssquery-yui.html) 13:58:00 INFO - PROCESS | 4926 | RSS: Main: 177221632 13:58:00 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | Cycle 1(1): loaded http://localhost:47990/tests/dromaeo/cssquery-yui.html (next: http://localhost:47990/tests/dromaeo/cssquery-dojo.html) 14:01:03 INFO - PROCESS | 4926 | RSS: Main: 268562432 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | __start_tp_report 14:01:03 INFO - PROCESS | 4926 | _x_x_mozilla_page_load 14:01:03 INFO - PROCESS | 4926 | _x_x_mozilla_page_load_details 14:01:03 INFO - PROCESS | 4926 | |i|pagename|runs| 14:01:03 INFO - PROCESS | 4926 | |0;dojo.html;3252.951205731914;3280.4587243104875;3282.671732826717;3298.7031167194946;3320.2695407010465;5607.523360514356;5676.290463692038;5702.486776190142;5713.428657134285;5721.113227449752;5704.172894930232;5728.942710572893;5736.4550367715;5748.19525266463;5761.971190144054;5160.200168973805;5175.948240517584;5193.9740301298625;5210.296609957664;5254.316938797951;5056.671316364443;5071.061853557104;5071.949280507185;5261.184516399964;5297.708626025567;5135.127028405162;5191.922121168185;5206.531412172903;5212.869678258036;5219.478052194769;5197;5205.6356055075785;5214.947850521484;5219.791208351661;5228.215767634847;5013.373328333958;5016.523430274118;5022.146235140035;5144.717040562768;5148.407933087706;5526.806561770359;5527.585431092644;5532.3637781655225;5603.831885043455;5684.403137670526;6011.6393016418715;6054.061620448838;6059.18201042858;6063.393660633946;6068.96965515174;5946.197263369433;6006.159137720722;6070.6357618543025;6102.420270074336;6108.083787431877;5621.269234999465;5671.546276297928;5680.005998950209;5685.715714214272;5706.172604972256;6248.5313601480075;6253.343398943045;6258.15514905491;6260.436560709558;6261.624302541771;5768.682722450306;5810.477057064884;5988.970055149696;6001;6010.54920880927;5981.162637230791;5989.251343582052;6007.669578173242;6070.605410648382;6103.962326404433;6004.3395226525045;6048.697565121726;6058.394160583995;6062.757489700362;6065.120557519135;6022.789202377896;6071.696415179224;6072.939270607326;6074.54440916933;6101.572889897753;4574.88562785933;4593.770311484413;4660.5106463821485;4688.179568575486;4694.0611877624615;4165.479315085614;4183.267928112568;4184.456020717348;4205.3902184183125;4212.241796476664;5890.646561206341;5903.0260007099205;5908.468237858613;5911.113332999998;5912.556558258148;5857.238558987389;5861.384554621789;6013.54898382623;6166.229221347332;6181.567290289727;5788.076562281713;5797.942020579824;5844.357120716718;5875.177475153482;5879.500242479352;5146.433892271847;5157.690538567623;5158.148905430562;5189.610779191576;5204.9739751301;6107.583301036431;6235.127082208403;6267.9373206268265;6271.4669253114;6272.1219029335925;5979;6020.488258498077;6030.427109424598;6083.513318934563;6116.816495505142;6293.465055470153;6333.904991425225;6339.492840572835;6343.143675603735;6343.777967771106;5848.64908105515;5921.052631578927;5968.970155149196;5976.372480889618;5979.402059793986;6011.549133814894;6030.969845150922;6039.969800150971;6050.848728781815;6067.24159480065;5954.642721436728;5962.105684147413;5995.070764031582;6005.699715014144;6009.308929472992;5949.434803693643;5965.433283838029;5965.940340596712;5968.253968253968;5969.164316995537;5713.257276554022;5810.128480727924;5816.3892791258;5824.21373114641;5855.3266374367495;5945.929732647992;5948.434898684618;5950.910736338872;5957.493613042767;5971.850703732268;5685.118806584986;5715.5713321500225;5784.5083167929515;5786.681732504752;5797.623154494944 14:01:03 INFO - PROCESS | 4926 | |1;ext.html;587.1926101610286;625.4308579192934;648.7794149989003;657.6448717692443;679.1476696745583;33369.332613347746;42979.14041719164;43737.12525749491;43770.56229437709;47121.057578848406;44140.3378949316;44416.777916110375;44463.77768111163;44507.77746111265;44522.33216501747;8411.915880841205;8810.735677929657;8936.597853096593;8945.507997060158;8954.37319387643;8436.746897593082;10335;10547.367157970508;10560.894391056107;10871.67384978448;10768.676939691783;10836.891631083667;10838.40388778617;10839.349639021684;10839.403832789194;10650.25448218625;10720.249582529226;10732.839007414856;10745.570177192982;10759.784804303948;10506.054455099002;10539.367637941748;10593.523291451904;10615.734606634818;10625.149988000941;42239.04326697133;42253.57746422527;42578.78710606427;42597;42671;3620.873269435557;3666.6699997000396;4252.957470425318;4264.125854199912;4303.7547405833875;32213.51679724806;32216.6778332216;32423.837880810683;32587.51118733221;32643.510347344807;4421.093675796454;4528.3207518872105;4546.408966834323;4568.657350698678;4627.074585082997;343792;344025;344693;344985;345159;59016;59160;59160.40839591549;59184.40815591789;59238.70380648069;39055.80472097678;39589.60410395859;39887.401688974685;42061.36907946383;42148.57851421508;18130.728039079426;18356.449306520826;18378;18389.632207356044;18391.90804045998;13032.869671303166;13117.868821311855;13418.865811341762;13796.241206733524;13967.092139010932;19430.022265530017;20460.260877825553;20551.897240513703;20591.79408205929;20596.691049634268;7402.7409040683315;7423.480356375002;7467.813304667427;7475.215102414277;7542.509736867086;984.7587341101332;1026.7227848480868;1029.6396261308632;1053.8208504554243;1132.318507017079;32252.67747322497;32697;32754.181145471553;32857.835710821295;33200.33599328;1148.3397046698183;1184.620921305174;1204.2052245517916;1208.9697757556132;1250.6498180509473;17266.050367229673;17622.91188544049;17691.292348306182;17966.820331796516;18350.07245290545;507463;512282;513560;515592;516787;61384.69307653522;61774.69112654408;62051.379486205464;62066;62085.37914620796;1308.8560258371572;1322.7486777512242;1348.2854087333728;1380.0201856681667;1395.379056319928;30205.848970755007;32294.515582265816;33501.43841640036;34114;34459;55855;56727;56802;56861.715691421276;56956;372662;374667;374772;376386;376682;24801.50396992086;26926.461470770868;27365;28984.710152899046;29038.27404314909;44717;44748.10503789971;44946.550534495545;44962.3255651172;45266;37816.43275350817;37947.76473644995;37951.81024094862;38917.41623875701;39239.41140882832;38739.41890871696;40517.39223911585;44599.55400445954;44770.77614611906;45723.54276457193;42463.787681061396;44955.32567011432;45064.098718026115;45639.54360456353;46258.30612540747;44037.779811100736;44746;44953.32570011518;46123.3081503771;46511;34512.48231276583;34952.825235874676;35625;35711;35718 14:01:03 INFO - PROCESS | 4926 | |2;jquery.html;424.60511724096597;426.12431453363325;426.1413252296623;437.2697595016322;438.8091180336554;18618.720719189223;19038.238470461183;20315;20334.593308133848;20634.071466783993;13950.79073813891;18822.717659235088;18952.905235473805;18975.905120474414;19932.800671993275;5532.7786888524415;5625.240592520008;5796.420357964212;5804.477597016267;5889.6171748836405;5884.735186916578;5894.292684877821;5904.61619994699;5917.970410147965;5919.082542205943;5883.852903677421;5891;5901.232839730829;5902.203202567663;5902.439268269529;5711.942880571182;5821.039528477796;5906.911396329058;5911.615744976584;5915.73379197935;5729.828105156852;5731.598788084836;5734.111212762028;5734.856628584277;5735.627184233012;13301;18495.907520462446;23523.411914702097;23627.763722362728;23726.169584064646;2317.849339792925;2348.823838212124;2404.2546810488802;2446.217210492643;2914.504534229165;9770.560324785401;10111.797764044752;10170.44062576558;10197.89802101977;12589.055820813475;1774.7249176377682;1919.7600299962505;1965.3416105604497;1973.8322242609395;2016.8890711010888;78896;83916;84027;85200;86626;10530.842037369446;10648.361098334124;10817.621383251548;11674.416279186007;12514.937425312815;17010.234539445755;17050.783398158408;17052.14739263032;17058.829411705974;17060.402885898933;8654.524001179869;8754.430961987451;8887.289016878634;8969.686060987835;8984.685536006209;8081.515109093473;8215.589220539068;8303.211194936472;8320.958395207985;8750.606222719993;4544.272916333371;4633.096546173464;4996.825111121166;5044.545990860766;5093.363329583802;1865.7574515312922;2021.5135383478405;2103.2112957640884;2164.664477006035;2181.694562761215;882.9602667879832;903.0425356956022;972.6060945317158;975.7365511312048;1056.4400867540216;18483.260669573334;18999.335023274118;19638;19693.901530492254;20126;977.3158788847835;1017.4404077757204;1022.7238645565656;1035.4770840725537;1045.5504133222787;10555.522226888243;10926.672199834018;11144.2756220847;11298.54805807775;11423.143264255214;450582;452627;459185;460508;460924;91840.54079729693;91996;92164;92179;94206.52896735472;1001.3191030099457;1018.5263852308713;1040.7294103532984;1043.5982146873357;1044.8850626431085;16518.752218716487;16562.081784386617;16868.66262674715;17350;17714.291428342724;42935.355969661105;43188.784056079516;43384.349234762136;44264.77867610641;44470.777646111565;59080.40919590921;59412.70293648677;59739.701301493216;60126.699366502886;60925;8516.148385161434;8577.8713319299;8616.224539791448;8700.869486957574;9190.678326258549;11962.461689223832;12786.680332991378;13102;13278.468861245441;13737.24445155526;6315.715792789428;8965.327600430124;10904.61833835812;11718.589849355232;11879.406029698654;11707.531698731955;11857.5849845255;12103.273803571814;12285.57000504978;12318.938405307916;12094.818577721517;12174.452149653463;12184.086193535342;12210.084243681937;12706.9199118076;8574.957125214334;8724.825503490021;8740.825183496167;8798.516081615317;8987.730368088967;11000.559977600806;11363.886361136283;11492.94253528727;11988.700282493008;12141.69645758863 14:01:03 INFO - PROCESS | 4926 | |3;mootools.html;1359.3475131936677;1450.4415799917033;1487.5760408283638;1643.7616545600888;1806.5212718629566;7422.331990120888;7864;8076.313513351365;8339.791505212357;8348.624311905964;8402.201790829862;8457.619407126678;8474.364422668294;8475.95762021189;8481.745547633565;2402.723686776017;2429.878506074689;2430.3802530354733;2431.8662473563945;2432.9391765205924;2375.4298968247667;2411.8914648840846;2454.6686197363397;2455.8649274289905;2461.88921498533;2383.2373640435067;2388.1163969331374;2395.940101497459;2408.590539608271;2423.0792298926385;2336.8714720690355;2362.444825466014;2368.39605900496;2375.5367703297866;2377.1085842809034;2345.3315804995664;2383.475635360218;2401.6670747735;2402.5795485789918;2402.627592723131;8195.672173113127;8304.916950830475;8430;8450.704225352145;8459.069502354736;5130.615203859726;5224.52979231871;5228.633995620308;5229.712365819878;5231.633785635007;3487.738419618539;3557;3607.6572725591027;3641.5448068991377;3648.7993160376436;1245.1221888568627;1256.0705078242036;1256.9811452828214;1306.640673814699;1315.0663029249245;11363.431828408546;11497.712557186138;11525.308481491138;11528.386025956457;11534.192606517547;4165.791710414467;4508.233600287959;4593.724376537419;4666.346711460398;4678.274867395559;8932.8660070099;8936.195742383086;8941.687040953535;8944.68693595721;8947.821043579223;5114.360704911886;5148.4079330876675;5154.252633368141;5164.638475306692;5191.143461328914;4271.124419493996;4376.037271800199;4825;4881.267809828554;4903.583195428358;3853.075261937171;3939.4878665773267;3968.900777480586;3991.720579559403;4035.6973227007506;1303.9608811735663;1313.9737205255842;1318.657149141211;1323.9801402978962;1325.0592079623382;924.371427429341;964.9710508684751;970.5050424283525;975.0834215837091;975.2539307429822;5640.069388550925;5683.431656834284;5838.854028649318;5851.64890106595;5904.084866845646;469.72521075170727;472.9760069449672;486.2608834571502;498.50647858619703;502.4724039758239;3812.2375524895133;4027.9194416111513;4058.127502586958;4060.147369052473;4088.6115818997146;10078.748031299276;10352.378857268588;10458.058774710313;10463.738406539745;10479.318844275098;10354.689359319233;10359.32664376813;10368;10382.377057376581;10403.791924161626;7756.4182686298755;7844.333231675258;7859.371250299962;7868;7874.291313781672;5821.650700957956;5992.820215393545;6108.144859719731;6113.755449781959;6128.938710612837;9101.544922753968;9505.239580833379;9570.33007689455;9570.56932438028;9578.856317155387;9457.243420526203;9597.088276613711;9599.616015359308;9693.854592180982;9703.12671859536;6917.619530925847;7127.429805615642;7129.429645628441;7412.592307423143;7550.924490755242;7937.2459616336355;8059.43583949118;8133.79665508367;8137.308328791883;8138.226868447488;7016.859662806817;7117.786466406016;7169.7849064528145;7182.784516464514;7352.77941661751;7135.5361901476235;7266.4913456059685;7305.41556675454;7328.890066649111;7581.886271705818;6855.280195579592;6980.930190698028;6986.196587392515;6987.580745155307;6988.196357418962;5123.871903202301;5195.142801437721;5202.609804264771;5212.244224587489;5404.675719456845;5009.974950125226;5229.555487783581;5260.9736951315;5302.1781623848365;5411.83764487066 14:01:03 INFO - PROCESS | 4926 | |4;prototype.html;358.8241761536848;360.3693536311455;361.9276144771046;364.20057972749817;365.661762869346;5354.41101478837;5382.89234215316;5391.245225668409;5400.488772720684;5434.646747961379;5344.251804747339;5351.170568561869;5354.785808567663;5371.221172929932;5373.387983604923;3762.6801721853676;4039.596040395966;4232.534421213664;4251.298535741599;4271.035323751078;4237.8516751913685;4259.76571288579;4270.743755374688;4271.017665936836;4297.183535128315;4226.753107833197;4243.702940794145;4269.7224680395675;4286.849960251392;4291.485021797389;4016.678665706751;4073.918521629551;4131.194417088669;4251.958270223784;4256.553061928515;3790.033541446913;3791;3794.5446546414605;3840.6557704803377;3845.596212397686;5150.150225212836;5172.379314482287;5221.034108689904;5227.529522343008;5262.394824595144;4772.379590653193;4785.928211076836;4817.156997525454;4830.130576496229;4841.741147301692;5519.172124181405;5522.5581953443625;5556.194351818964;5563.582731295129;5586.553075753971;4160.604742549453;4170.270202714512;4188.7905604719945;4197.517285512145;4212.936805947883;5549.861253468655;5789.507891829199;5790.594658373873;5811.418858114197;5857.794977175821;4189.518205406356;4214.894627634334;4220.767857767853;4220.894477638022;4225.429567008415;4276.310857211384;4288.31386978082;4306.569343065668;4314.633256173198;4330.133973205308;4762.214234651313;4769.642276829251;4803.967147063398;4865.659403841697;4885.972321486614;5590.636608620427;5594.24477695514;5598.860028499239;5623.297087864017;5643.802466913638;1399.9580012599638;1422.4665750343622;1425.5673048586311;1438.467766926244;1440.2582669924998;975.7902051059057;990.9554070066864;1007.1942446043282;1017.8167929772712;1025.8409946458312;5276.762545685453;5284.286621306074;5303.920441193385;5347.491988261109;5439.809606663748;5560.2493663355735;5629.211910332557;5691.089425691869;5700.543956483471;5713.8285851424525;5240.528352448222;5273.34083239595;5289.2859463972645;5495.450454954473;5541.722913854372;2948.3047247832155;2964.214483161964;2968.6586042604945;2975.285931376454;2996.7602591792606;5697.9430205698245;5803.564732645069;5902.203202567598;5940.31686356066;5955.34491205967;5607.710226647812;5671.234383358194;5679.375268720438;5717.2281741965135;5748.856278593069;5865.912011319833;6005.939940600625;6006.639601623917;6008.128821320972;6022.45797878193;5781.884362312814;5807.883842323045;5809.5352371811;5811.912821307768;5819.592628516132;5539.612227144061;5668.37647858727;5687.516561092353;5690.914636280542;5713.314402271754;5916.467517923407;5970.5223582114195;5971.8354920790025;5972.552058595709;6019.18740969981;4040.8383664653084;4211.494620645542;4236.3857240700545;4285.3571964204375;4302.376155457504;5725.341585717696;5730.656160630376;5734.168545560787;5737.512311453574;5764.827055188351;5642;5643.181738647791;5646.435356464321;5674.460926212003;5674.4892959633835;5420.4037555868035;5607.682194684327;5636.577256705846;5638.097904335286;5672.914906276326;5673.205751194919;5680.176374425768;5680.829575112753;5691.857703557444;5694.259746232963;5594.188842617879;5734.483896449173;5736.168255602831;5743.511801496752;5747.8563035924435;5523.585731070106;5565.944340556704;5577;5586.832395028156;5593.9233421618765 14:01:03 INFO - PROCESS | 4926 | |5;yui.html;774.4269240761835;779.1624004195489;807.676929228309;813.4346629092782;863.594110767939;14439.63900902478;14772.556823295305;15136.545903622882;15256.771148432754;15468.535943921686;13142.211467311969;14071.08537945033;14201.360938757754;14212.644683882909;14227.573172804807;792.3344390711816;829.1169904052165;830.9459885107478;831.4637059096879;831.6216121664659;821.1993306526119;823.7816978500703;823.8887750153713;824.114077366833;824.196408501709;866.6663334616187;868.7784614923186;869.0483920107472;869.0483920107472;869.4696235296464;858.716623514242;859.2438653984516;862.9482231066124;863.8056437301557;863.8531449653542;628.5631486117151;630.8738252349549;632.7658766256468;633.1262857256955;633.1484153813115;13401.396937137852;13656.180629162282;13728.039037267396;14866.182359970197;15029.774553381709;14565.854341456556;14737;15034.774478382833;15371.461998829987;15557.922210388875;11923.403829808562;11925.165238433314;11946.761064778655;12026.939865300617;12179.817302740466;3870;3893.7274390792654;3942.0539070622845;3989.940150897739;3994.0414300567945;65138;68140;68336.3166368335;69005.3099469004;69237;10376.844347334796;10873.347599144077;10969.286996345212;11107.111431085494;11387.08903287735;15470;15843.683126337639;20426.489337766674;20507.58984820295;20615.278465253945;6382.84042898922;7640.885386719204;7908.604569771488;8213.507189568645;8598.5700714964;4601.539846015372;4627.953720462752;4725;4728.196206644879;4741.762911854394;3476.652334766503;3558.964410355915;3560.9821950890077;3563.073600863794;3600.4419315006216;634.6921742954663;645.6416688737795;653.5294587896707;660.5640277416882;669.6149713914518;716.2372073741553;747.8130467383154;749.6326799868025;795.2961628958413;809.5587904592012;9227.123423274777;9557.948625651174;9834.114929656365;10172.847407288897;10242.129418999468;685.9416949559244;782.3701919954491;784.4822417204732;791.6318911705969;795.6976348987348;4769.308450274691;4834.444038935497;4839.322494850724;4839.346688197048;4880;57137.71431142818;58181;58842.70578647165;94793.52603237078;97599.51200243953;50733.23900141501;68370;82520;84664.15335846563;85758.14241857626;76631;76814;77785;78404.60797695976;79688;9086.772830679021;9480.336376453582;9542.761430964281;9570.56932438028;9593.808123837345;49388.25917611167;50816.491835082656;51090;51269.487305127965;51657.483425165265;40694.1861162781;62114.37885621086;68422.65788671025;69131.65434172998;70944.29055709377;11199.88800112021;11374.829377559177;11651.951846018768;11753.47109380097;11947.880521194676;13297.468101275841;13322.666933326744;13507.864921351054;13595.86404135946;13728.107673001223;12190.939045305071;12358.196717213352;12492.812607811073;13147.079704420981;13172.73654526885;12218.572349967708;12292.692682683004;12451.43968521437;12816.102872799187;13167.67080822987;12384.380780961095;12398.752024959269;12432.875671243533;13341.466341346626;13370.197788132744;10361.63734269297;10450.059494645518;10735;10850.45747712627;11126.332420054823;10869.945650271698;10982.835257470984;11165.441727913734;11192.38441885704;11272.605458808903 14:01:03 INFO - PROCESS | 4926 | __end_tp_report 14:01:03 INFO - PROCESS | 4926 | __start_cc_report 14:01:03 INFO - PROCESS | 4926 | _x_x_mozilla_cycle_collect,1040 14:01:03 INFO - PROCESS | 4926 | __end_cc_report 14:01:03 INFO - PROCESS | 4926 | __startTimestamp1484949663403__endTimestamp 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | ------- Summary: start ------- 14:01:03 INFO - PROCESS | 4926 | Number of tests: 6 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | [#0] dojo.html Cycles:165 Average:5636.64 Median:5861.38 stddev:635.34 (10.8%) stddev-sans-first:609.15 14:01:03 INFO - PROCESS | 4926 | Values: 3253.0 3280.5 3282.7 3298.7 3320.3 5607.5 5676.3 5702.5 5713.4 5721.1 5704.2 5728.9 5736.5 5748.2 5762.0 5160.2 5175.9 5194.0 5210.3 5254.3 5056.7 5071.1 5071.9 5261.2 5297.7 5135.1 5191.9 5206.5 5212.9 5219.5 5197.0 5205.6 5214.9 5219.8 5228.2 5013.4 5016.5 5022.1 5144.7 5148.4 5526.8 5527.6 5532.4 5603.8 5684.4 6011.6 6054.1 6059.2 6063.4 6069.0 5946.2 6006.2 6070.6 6102.4 6108.1 5621.3 5671.5 5680.0 5685.7 5706.2 6248.5 6253.3 6258.2 6260.4 6261.6 5768.7 5810.5 5989.0 6001.0 6010.5 5981.2 5989.3 6007.7 6070.6 6104.0 6004.3 6048.7 6058.4 6062.8 6065.1 6022.8 6071.7 6072.9 6074.5 6101.6 4574.9 4593.8 4660.5 4688.2 4694.1 4165.5 4183.3 4184.5 4205.4 4212.2 5890.6 5903.0 5908.5 5911.1 5912.6 5857.2 5861.4 6013.5 6166.2 6181.6 5788.1 5797.9 5844.4 5875.2 5879.5 5146.4 5157.7 5158.1 5189.6 5205.0 6107.6 6235.1 6267.9 6271.5 6272.1 5979.0 6020.5 6030.4 6083.5 6116.8 6293.5 6333.9 6339.5 6343.1 6343.8 5848.6 5921.1 5969.0 5976.4 5979.4 6011.5 6031.0 6040.0 6050.8 6067.2 5954.6 5962.1 5995.1 6005.7 6009.3 5949.4 5965.4 5965.9 5968.3 5969.2 5713.3 5810.1 5816.4 5824.2 5855.3 5945.9 5948.4 5950.9 5957.5 5971.9 5685.1 5715.6 5784.5 5786.7 5797.6 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | [#1] ext.html Cycles:180 Average:58287.96 Median:207249.22 stddev:110181.05 (53.2%) stddev-sans-first:110404.96 14:01:03 INFO - PROCESS | 4926 | Values: 587.2 625.4 648.8 657.6 679.1 33369.3 42979.1 43737.1 43770.6 47121.1 44140.3 44416.8 44463.8 44507.8 44522.3 8411.9 8810.7 8936.6 8945.5 8954.4 8436.7 10335.0 10547.4 10560.9 10871.7 10768.7 10836.9 10838.4 10839.3 10839.4 10650.3 10720.2 10732.8 10745.6 10759.8 10506.1 10539.4 10593.5 10615.7 10625.1 42239.0 42253.6 42578.8 42597.0 42671.0 3620.9 3666.7 4253.0 4264.1 4303.8 32213.5 32216.7 32423.8 32587.5 32643.5 4421.1 4528.3 4546.4 4568.7 4627.1 343792.0 344025.0 344693.0 344985.0 345159.0 59016.0 59160.0 59160.4 59184.4 59238.7 39055.8 39589.6 39887.4 42061.4 42148.6 18130.7 18356.4 18378.0 18389.6 18391.9 13032.9 13117.9 13418.9 13796.2 13967.1 19430.0 20460.3 20551.9 20591.8 20596.7 7402.7 7423.5 7467.8 7475.2 7542.5 984.8 1026.7 1029.6 1053.8 1132.3 32252.7 32697.0 32754.2 32857.8 33200.3 1148.3 1184.6 1204.2 1209.0 1250.6 17266.1 17622.9 17691.3 17966.8 18350.1 507463.0 512282.0 513560.0 515592.0 516787.0 61384.7 61774.7 62051.4 62066.0 62085.4 1308.9 1322.7 1348.3 1380.0 1395.4 30205.8 32294.5 33501.4 34114.0 34459.0 55855.0 56727.0 56802.0 56861.7 56956.0 372662.0 374667.0 374772.0 376386.0 376682.0 24801.5 26926.5 27365.0 28984.7 29038.3 44717.0 44748.1 44946.6 44962.3 45266.0 37816.4 37947.8 37951.8 38917.4 39239.4 38739.4 40517.4 44599.6 44770.8 45723.5 42463.8 44955.3 45064.1 45639.5 46258.3 44037.8 44746.0 44953.3 46123.3 46511.0 34512.5 34952.8 35625.0 35711.0 35718.0 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | [#2] jquery.html Cycles:180 Average:28296.42 Median:2680.36 stddev:75595.35 (2820.3%) stddev-sans-first:75778.45 14:01:03 INFO - PROCESS | 4926 | Values: 424.6 426.1 426.1 437.3 438.8 18618.7 19038.2 20315.0 20334.6 20634.1 13950.8 18822.7 18952.9 18975.9 19932.8 5532.8 5625.2 5796.4 5804.5 5889.6 5884.7 5894.3 5904.6 5918.0 5919.1 5883.9 5891.0 5901.2 5902.2 5902.4 5711.9 5821.0 5906.9 5911.6 5915.7 5729.8 5731.6 5734.1 5734.9 5735.6 13301.0 18495.9 23523.4 23627.8 23726.2 2317.8 2348.8 2404.3 2446.2 2914.5 9770.6 10111.8 10170.4 10197.9 12589.1 1774.7 1919.8 1965.3 1973.8 2016.9 78896.0 83916.0 84027.0 85200.0 86626.0 10530.8 10648.4 10817.6 11674.4 12514.9 17010.2 17050.8 17052.1 17058.8 17060.4 8654.5 8754.4 8887.3 8969.7 8984.7 8081.5 8215.6 8303.2 8321.0 8750.6 4544.3 4633.1 4996.8 5044.5 5093.4 1865.8 2021.5 2103.2 2164.7 2181.7 883.0 903.0 972.6 975.7 1056.4 18483.3 18999.3 19638.0 19693.9 20126.0 977.3 1017.4 1022.7 1035.5 1045.6 10555.5 10926.7 11144.3 11298.5 11423.1 450582.0 452627.0 459185.0 460508.0 460924.0 91840.5 91996.0 92164.0 92179.0 94206.5 1001.3 1018.5 1040.7 1043.6 1044.9 16518.8 16562.1 16868.7 17350.0 17714.3 42935.4 43188.8 43384.3 44264.8 44470.8 59080.4 59412.7 59739.7 60126.7 60925.0 8516.1 8577.9 8616.2 8700.9 9190.7 11962.5 12786.7 13102.0 13278.5 13737.2 6315.7 8965.3 10904.6 11718.6 11879.4 11707.5 11857.6 12103.3 12285.6 12318.9 12094.8 12174.5 12184.1 12210.1 12706.9 8575.0 8724.8 8740.8 8798.5 8987.7 11000.6 11363.9 11492.9 11988.7 12141.7 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | [#3] mootools.html Cycles:180 Average:5573.97 Median:5159.45 stddev:3036.24 (58.8%) stddev-sans-first:3028.23 14:01:03 INFO - PROCESS | 4926 | Values: 1359.3 1450.4 1487.6 1643.8 1806.5 7422.3 7864.0 8076.3 8339.8 8348.6 8402.2 8457.6 8474.4 8476.0 8481.7 2402.7 2429.9 2430.4 2431.9 2432.9 2375.4 2411.9 2454.7 2455.9 2461.9 2383.2 2388.1 2395.9 2408.6 2423.1 2336.9 2362.4 2368.4 2375.5 2377.1 2345.3 2383.5 2401.7 2402.6 2402.6 8195.7 8304.9 8430.0 8450.7 8459.1 5130.6 5224.5 5228.6 5229.7 5231.6 3487.7 3557.0 3607.7 3641.5 3648.8 1245.1 1256.1 1257.0 1306.6 1315.1 11363.4 11497.7 11525.3 11528.4 11534.2 4165.8 4508.2 4593.7 4666.3 4678.3 8932.9 8936.2 8941.7 8944.7 8947.8 5114.4 5148.4 5154.3 5164.6 5191.1 4271.1 4376.0 4825.0 4881.3 4903.6 3853.1 3939.5 3968.9 3991.7 4035.7 1304.0 1314.0 1318.7 1324.0 1325.1 924.4 965.0 970.5 975.1 975.3 5640.1 5683.4 5838.9 5851.6 5904.1 469.7 473.0 486.3 498.5 502.5 3812.2 4027.9 4058.1 4060.1 4088.6 10078.7 10352.4 10458.1 10463.7 10479.3 10354.7 10359.3 10368.0 10382.4 10403.8 7756.4 7844.3 7859.4 7868.0 7874.3 5821.7 5992.8 6108.1 6113.8 6128.9 9101.5 9505.2 9570.3 9570.6 9578.9 9457.2 9597.1 9599.6 9693.9 9703.1 6917.6 7127.4 7129.4 7412.6 7550.9 7937.2 8059.4 8133.8 8137.3 8138.2 7016.9 7117.8 7169.8 7182.8 7352.8 7135.5 7266.5 7305.4 7328.9 7581.9 6855.3 6980.9 6986.2 6987.6 6988.2 5123.9 5195.1 5202.6 5212.2 5404.7 5010.0 5229.6 5261.0 5302.2 5411.8 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | [#4] prototype.html Cycles:180 Average:4751.70 Median:5378.14 stddev:1376.50 (25.6%) stddev-sans-first:1340.29 14:01:03 INFO - PROCESS | 4926 | Values: 358.8 360.4 361.9 364.2 365.7 5354.4 5382.9 5391.2 5400.5 5434.6 5344.3 5351.2 5354.8 5371.2 5373.4 3762.7 4039.6 4232.5 4251.3 4271.0 4237.9 4259.8 4270.7 4271.0 4297.2 4226.8 4243.7 4269.7 4286.8 4291.5 4016.7 4073.9 4131.2 4252.0 4256.6 3790.0 3791.0 3794.5 3840.7 3845.6 5150.2 5172.4 5221.0 5227.5 5262.4 4772.4 4785.9 4817.2 4830.1 4841.7 5519.2 5522.6 5556.2 5563.6 5586.6 4160.6 4170.3 4188.8 4197.5 4212.9 5549.9 5789.5 5790.6 5811.4 5857.8 4189.5 4214.9 4220.8 4220.9 4225.4 4276.3 4288.3 4306.6 4314.6 4330.1 4762.2 4769.6 4804.0 4865.7 4886.0 5590.6 5594.2 5598.9 5623.3 5643.8 1400.0 1422.5 1425.6 1438.5 1440.3 975.8 991.0 1007.2 1017.8 1025.8 5276.8 5284.3 5303.9 5347.5 5439.8 5560.2 5629.2 5691.1 5700.5 5713.8 5240.5 5273.3 5289.3 5495.5 5541.7 2948.3 2964.2 2968.7 2975.3 2996.8 5697.9 5803.6 5902.2 5940.3 5955.3 5607.7 5671.2 5679.4 5717.2 5748.9 5865.9 6005.9 6006.6 6008.1 6022.5 5781.9 5807.9 5809.5 5811.9 5819.6 5539.6 5668.4 5687.5 5690.9 5713.3 5916.5 5970.5 5971.8 5972.6 6019.2 4040.8 4211.5 4236.4 4285.4 4302.4 5725.3 5730.7 5734.2 5737.5 5764.8 5642.0 5643.2 5646.4 5674.5 5674.5 5420.4 5607.7 5636.6 5638.1 5672.9 5673.2 5680.2 5680.8 5691.9 5694.3 5594.2 5734.5 5736.2 5743.5 5747.9 5523.6 5565.9 5577.0 5586.8 5593.9 14:01:03 INFO - PROCESS | 4926 | 14:01:03 INFO - PROCESS | 4926 | [#5] yui.html Cycles:180 Average:17841.56 Median:4839.33 stddev:23683.20 (489.4%) stddev-sans-first:23714.97 14:01:03 INFO - PROCESS | 4926 | Values: 774.4 779.2 807.7 813.4 863.6 14439.6 14772.6 15136.5 15256.8 15468.5 13142.2 14071.1 14201.4 14212.6 14227.6 792.3 829.1 830.9 831.5 831.6 821.2 823.8 823.9 824.1 824.2 866.7 868.8 869.0 869.0 869.5 858.7 859.2 862.9 863.8 863.9 628.6 630.9 632.8 633.1 633.1 13401.4 13656.2 13728.0 14866.2 15029.8 14565.9 14737.0 15034.8 15371.5 15557.9 11923.4 11925.2 11946.8 12026.9 12179.8 3870.0 3893.7 3942.1 3989.9 3994.0 65138.0 68140.0 68336.3 69005.3 69237.0 10376.8 10873.3 10969.3 11107.1 11387.1 15470.0 15843.7 20426.5 20507.6 20615.3 6382.8 7640.9 7908.6 8213.5 8598.6 4601.5 4628.0 4725.0 4728.2 4741.8 3476.7 3559.0 3561.0 3563.1 3600.4 634.7 645.6 653.5 660.6 669.6 716.2 747.8 749.6 795.3 809.6 9227.1 9557.9 9834.1 10172.8 10242.1 685.9 782.4 784.5 791.6 795.7 4769.3 4834.4 4839.3 4839.3 4880.0 57137.7 58181.0 58842.7 94793.5 97599.5 50733.2 68370.0 82520.0 84664.2 85758.1 76631.0 76814.0 77785.0 78404.6 79688.0 9086.8 9480.3 9542.8 9570.6 9593.8 49388.3 50816.5 51090.0 51269.5 51657.5 40694.2 62114.4 68422.7 69131.7 70944.3 11199.9 11374.8 11652.0 11753.5 11947.9 13297.5 13322.7 13507.9 13595.9 13728.1 12190.9 12358.2 12492.8 13147.1 13172.7 12218.6 12292.7 12451.4 12816.1 13167.7 12384.4 12398.8 12432.9 13341.5 13370.2 10361.6 10450.1 10735.0 10850.5 11126.3 10869.9 10982.8 11165.4 11192.4 11272.6 14:01:03 INFO - PROCESS | 4926 | -------- Summary: end -------- 14:01:03 INFO - PROCESS | 4926 | 14:01:04 INFO - TEST-INFO | 4926: exit 0 14:01:04 INFO - TEST-OK | dromaeo_css | took 1114708ms 14:01:04 INFO - TEST-START | kraken 14:01:04 INFO - operating with platform_type : linux_ 14:01:04 INFO - Initialising browser for kraken test... 14:01:04 INFO - TEST-INFO | started process 5245 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2objFJ/profile http://localhost:47990/getInfo.html) 14:01:08 INFO - PROCESS | 5245 | __metrics Screen width/height:1600/1200 14:01:08 INFO - PROCESS | 5245 | colorDepth:24 14:01:08 INFO - PROCESS | 5245 | Browser inner width/height: 1024/697 14:01:08 INFO - PROCESS | 5245 | __metrics 14:01:08 INFO - PROCESS | 5245 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:01:08 INFO - PROCESS | 5245 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:01:09 INFO - PROCESS | 5245 | console.error: PushService: 14:01:09 INFO - PROCESS | 5245 | stateChangeProcessEnqueue: Error transitioning state 14:01:09 INFO - PROCESS | 5245 | UnknownError 14:01:09 INFO - TEST-INFO | 5245: exit 0 14:01:09 INFO - Browser initialized. 14:01:09 INFO - Running cycle 1/1 for kraken test... 14:01:09 INFO - TEST-INFO | started process 5342 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2objFJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:01:21 INFO - PROCESS | 5342 | RSS: Main: 143851520 14:01:21 INFO - PROCESS | 5342 | 14:01:23 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:47990/tests/kraken/driver.html?testName=audio-beat-detection) 14:01:24 INFO - PROCESS | 5342 | RSS: Main: 184684544 14:01:24 INFO - PROCESS | 5342 | 14:01:27 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:47990/tests/kraken/driver.html?testName=audio-dft) 14:01:27 INFO - PROCESS | 5342 | RSS: Main: 174874624 14:01:27 INFO - PROCESS | 5342 | 14:01:30 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:47990/tests/kraken/driver.html?testName=audio-fft) 14:01:31 INFO - PROCESS | 5342 | RSS: Main: 266186752 14:01:31 INFO - PROCESS | 5342 | 14:01:33 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:47990/tests/kraken/driver.html?testName=audio-oscillator) 14:01:33 INFO - PROCESS | 5342 | RSS: Main: 173170688 14:01:33 INFO - PROCESS | 5342 | 14:01:36 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:47990/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:01:36 INFO - PROCESS | 5342 | RSS: Main: 175308800 14:01:36 INFO - PROCESS | 5342 | 14:01:39 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:47990/tests/kraken/driver.html?testName=imaging-darkroom) 14:01:40 INFO - PROCESS | 5342 | RSS: Main: 214216704 14:01:40 INFO - PROCESS | 5342 | 14:01:43 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:47990/tests/kraken/driver.html?testName=imaging-desaturate) 14:01:44 INFO - PROCESS | 5342 | RSS: Main: 213348352 14:01:44 INFO - PROCESS | 5342 | 14:01:46 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:47990/tests/kraken/driver.html?testName=json-parse-financial) 14:01:47 INFO - PROCESS | 5342 | RSS: Main: 211632128 14:01:47 INFO - PROCESS | 5342 | 14:01:48 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:47990/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:01:49 INFO - PROCESS | 5342 | RSS: Main: 173940736 14:01:49 INFO - PROCESS | 5342 | 14:01:51 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:01:51 INFO - PROCESS | 5342 | RSS: Main: 180195328 14:01:51 INFO - PROCESS | 5342 | 14:01:53 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:01:54 INFO - PROCESS | 5342 | RSS: Main: 189763584 14:01:54 INFO - PROCESS | 5342 | 14:01:56 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:01:57 INFO - PROCESS | 5342 | RSS: Main: 197677056 14:01:57 INFO - PROCESS | 5342 | 14:02:00 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:02:00 INFO - PROCESS | 5342 | RSS: Main: 194969600 14:02:00 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | Cycle 1(1): loaded http://localhost:47990/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:47990/tests/kraken/driver.html?testName=ai-astar) 14:02:02 INFO - PROCESS | 5342 | RSS: Main: 246079488 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | __start_tp_report 14:02:02 INFO - PROCESS | 5342 | _x_x_mozilla_page_load 14:02:02 INFO - PROCESS | 5342 | _x_x_mozilla_page_load_details 14:02:02 INFO - PROCESS | 5342 | |i|pagename|runs| 14:02:02 INFO - PROCESS | 5342 | |0;ai-astar;99;110;109;110;112;109;110;111;110;110 14:02:02 INFO - PROCESS | 5342 | |1;audio-beat-detection;142;118;117;157;122;118;124;116;151;118 14:02:02 INFO - PROCESS | 5342 | |2;audio-dft;161;161;162;159;160;161;159;160;159;159 14:02:02 INFO - PROCESS | 5342 | |3;audio-fft;75;75;76;122;75;76;74;75;107;74 14:02:02 INFO - PROCESS | 5342 | |4;audio-oscillator;81;82;105;82;82;82;104;82;81;108 14:02:02 INFO - PROCESS | 5342 | |5;imaging-gaussian-blur;117;117;117;117;117;116;117;117;117;117 14:02:02 INFO - PROCESS | 5342 | |6;imaging-darkroom;146;146;146;146;146;147;146;145;145;145 14:02:02 INFO - PROCESS | 5342 | |7;imaging-desaturate;85;85;85;84;85;84;85;84;85;85 14:02:02 INFO - PROCESS | 5342 | |8;json-parse-financial;52;51;52;51;52;52;53;51;51;52 14:02:02 INFO - PROCESS | 5342 | |9;json-stringify-tinderbox;57;59;57;58;59;58;58;62;59;59 14:02:02 INFO - PROCESS | 5342 | |10;stanford-crypto-aes;75;73;78;80;75;74;74;74;75;75 14:02:02 INFO - PROCESS | 5342 | |11;stanford-crypto-ccm;126;121;125;130;128;122;119;119;157;120 14:02:02 INFO - PROCESS | 5342 | |12;stanford-crypto-pbkdf2;156;153;153;151;152;154;155;150;154;152 14:02:02 INFO - PROCESS | 5342 | |13;stanford-crypto-sha256-iterative;63;65;61;64;62;63;67;62;60;60 14:02:02 INFO - PROCESS | 5342 | __end_tp_report 14:02:02 INFO - PROCESS | 5342 | __start_cc_report 14:02:02 INFO - PROCESS | 5342 | _x_x_mozilla_cycle_collect,1601 14:02:02 INFO - PROCESS | 5342 | __end_cc_report 14:02:02 INFO - PROCESS | 5342 | __startTimestamp1484949722652__endTimestamp 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | ------- Summary: start ------- 14:02:02 INFO - PROCESS | 5342 | Number of tests: 14 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#0] ai-astar Cycles:10 Average:109.00 Median:110.00 stddev:3.62 (3.3%) stddev-sans-first:0.93 14:02:02 INFO - PROCESS | 5342 | Values: 99.0 110.0 109.0 110.0 112.0 109.0 110.0 111.0 110.0 110.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:123.00 stddev:15.57 (12.7%) stddev-sans-first:15.71 14:02:02 INFO - PROCESS | 5342 | Values: 142.0 118.0 117.0 157.0 122.0 118.0 124.0 116.0 151.0 118.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.50 stddev:1.10 (0.7%) stddev-sans-first:1.12 14:02:02 INFO - PROCESS | 5342 | Values: 161.0 161.0 162.0 159.0 160.0 161.0 159.0 160.0 159.0 159.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#3] audio-fft Cycles:10 Average:82.90 Median:75.00 stddev:17.04 (22.7%) stddev-sans-first:17.83 14:02:02 INFO - PROCESS | 5342 | Values: 75.0 75.0 76.0 122.0 75.0 76.0 74.0 75.0 107.0 74.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#4] audio-oscillator Cycles:10 Average:88.90 Median:82.00 stddev:11.62 (14.2%) stddev-sans-first:11.97 14:02:02 INFO - PROCESS | 5342 | Values: 81.0 82.0 105.0 82.0 82.0 82.0 104.0 82.0 81.0 108.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 14:02:02 INFO - PROCESS | 5342 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 117.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 14:02:02 INFO - PROCESS | 5342 | Values: 146.0 146.0 146.0 146.0 146.0 147.0 146.0 145.0 145.0 145.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 14:02:02 INFO - PROCESS | 5342 | Values: 85.0 85.0 85.0 84.0 85.0 84.0 85.0 84.0 85.0 85.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.67 (1.3%) stddev-sans-first:0.71 14:02:02 INFO - PROCESS | 5342 | Values: 52.0 51.0 52.0 51.0 52.0 52.0 53.0 51.0 51.0 52.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:1.43 (2.4%) stddev-sans-first:1.39 14:02:02 INFO - PROCESS | 5342 | Values: 57.0 59.0 57.0 58.0 59.0 58.0 58.0 62.0 59.0 59.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#10] stanford-crypto-aes Cycles:10 Average:75.30 Median:75.00 stddev:2.11 (2.8%) stddev-sans-first:2.24 14:02:02 INFO - PROCESS | 5342 | Values: 75.0 73.0 78.0 80.0 75.0 74.0 74.0 74.0 75.0 75.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#11] stanford-crypto-ccm Cycles:10 Average:126.70 Median:125.50 stddev:11.31 (9.0%) stddev-sans-first:12.00 14:02:02 INFO - PROCESS | 5342 | Values: 126.0 121.0 125.0 130.0 128.0 122.0 119.0 119.0 157.0 120.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:153.50 stddev:1.83 (1.2%) stddev-sans-first:1.58 14:02:02 INFO - PROCESS | 5342 | Values: 156.0 153.0 153.0 151.0 152.0 154.0 155.0 150.0 154.0 152.0 14:02:02 INFO - PROCESS | 5342 | 14:02:02 INFO - PROCESS | 5342 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:63.00 stddev:2.21 (3.5%) stddev-sans-first:2.35 14:02:02 INFO - PROCESS | 5342 | Values: 63.0 65.0 61.0 64.0 62.0 63.0 67.0 62.0 60.0 60.0 14:02:02 INFO - PROCESS | 5342 | -------- Summary: end -------- 14:02:02 INFO - PROCESS | 5342 | 14:02:03 INFO - TEST-INFO | 5342: exit 0 14:02:03 INFO - TEST-OK | kraken | took 59505ms 14:02:03 INFO - SUITE-END | took 1174s 14:02:03 INFO - Completed test suite (00:19:34) 14:02:03 INFO - javascript benchmark 14:02:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3252.951205731914, 3280.4587243104875, 3282.671732826717, 3298.7031167194946, 3320.2695407010465, 5607.523360514356, 5676.290463692038, 5702.486776190142, 5713.428657134285, 5721.113227449752, 5704.172894930232, 5728.942710572893, 5736.4550367715, 5748.19525266463, 5761.971190144054, 5160.200168973805, 5175.948240517584, 5193.9740301298625, 5210.296609957664, 5254.316938797951, 5056.671316364443, 5071.061853557104, 5071.949280507185, 5261.184516399964, 5297.708626025567, 5135.127028405162, 5191.922121168185, 5206.531412172903, 5212.869678258036, 5219.478052194769, 5197.0, 5205.6356055075785, 5214.947850521484, 5219.791208351661, 5228.215767634847, 5013.373328333958, 5016.523430274118, 5022.146235140035, 5144.717040562768, 5148.407933087706, 5526.806561770359, 5527.585431092644, 5532.3637781655225, 5603.831885043455, 5684.403137670526, 6011.6393016418715, 6054.061620448838, 6059.18201042858, 6063.393660633946, 6068.96965515174, 5946.197263369433, 6006.159137720722, 6070.6357618543025, 6102.420270074336, 6108.083787431877, 5621.269234999465, 5671.546276297928, 5680.005998950209, 5685.715714214272, 5706.172604972256, 6248.5313601480075, 6253.343398943045, 6258.15514905491, 6260.436560709558, 6261.624302541771, 5768.682722450306, 5810.477057064884, 5988.970055149696, 6001.0, 6010.54920880927, 5981.162637230791, 5989.251343582052, 6007.669578173242, 6070.605410648382, 6103.962326404433, 6004.3395226525045, 6048.697565121726, 6058.394160583995, 6062.757489700362, 6065.120557519135, 6022.789202377896, 6071.696415179224, 6072.939270607326, 6074.54440916933, 6101.572889897753, 4574.88562785933, 4593.770311484413, 4660.5106463821485, 4688.179568575486, 4694.0611877624615, 4165.479315085614, 4183.267928112568, 4184.456020717348, 4205.3902184183125, 4212.241796476664, 5890.646561206341, 5903.0260007099205, 5908.468237858613, 5911.113332999998, 5912.556558258148, 5857.238558987389, 5861.384554621789, 6013.54898382623, 6166.229221347332, 6181.567290289727, 5788.076562281713, 5797.942020579824, 5844.357120716718, 5875.177475153482, 5879.500242479352, 5146.433892271847, 5157.690538567623, 5158.148905430562, 5189.610779191576, 5204.9739751301, 6107.583301036431, 6235.127082208403, 6267.9373206268265, 6271.4669253114, 6272.1219029335925, 5979.0, 6020.488258498077, 6030.427109424598, 6083.513318934563, 6116.816495505142, 6293.465055470153, 6333.904991425225, 6339.492840572835, 6343.143675603735, 6343.777967771106, 5848.64908105515, 5921.052631578927, 5968.970155149196, 5976.372480889618, 5979.402059793986, 6011.549133814894, 6030.969845150922, 6039.969800150971, 6050.848728781815, 6067.24159480065, 5954.642721436728, 5962.105684147413, 5995.070764031582, 6005.699715014144, 6009.308929472992, 5949.434803693643, 5965.433283838029, 5965.940340596712, 5968.253968253968, 5969.164316995537, 5713.257276554022, 5810.128480727924, 5816.3892791258, 5824.21373114641, 5855.3266374367495, 5945.929732647992, 5948.434898684618, 5950.910736338872, 5957.493613042767, 5971.850703732268, 5685.118806584986, 5715.5713321500225, 5784.5083167929515, 5786.681732504752, 5797.623154494944], "value": 5594.037068029863, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.1926101610286, 625.4308579192934, 648.7794149989003, 657.6448717692443, 679.1476696745583, 33369.332613347746, 42979.14041719164, 43737.12525749491, 43770.56229437709, 47121.057578848406, 44140.3378949316, 44416.777916110375, 44463.77768111163, 44507.77746111265, 44522.33216501747, 8411.915880841205, 8810.735677929657, 8936.597853096593, 8945.507997060158, 8954.37319387643, 8436.746897593082, 10335.0, 10547.367157970508, 10560.894391056107, 10871.67384978448, 10768.676939691783, 10836.891631083667, 10838.40388778617, 10839.349639021684, 10839.403832789194, 10650.25448218625, 10720.249582529226, 10732.839007414856, 10745.570177192982, 10759.784804303948, 10506.054455099002, 10539.367637941748, 10593.523291451904, 10615.734606634818, 10625.149988000941, 42239.04326697133, 42253.57746422527, 42578.78710606427, 42597.0, 42671.0, 3620.873269435557, 3666.6699997000396, 4252.957470425318, 4264.125854199912, 4303.7547405833875, 32213.51679724806, 32216.6778332216, 32423.837880810683, 32587.51118733221, 32643.510347344807, 4421.093675796454, 4528.3207518872105, 4546.408966834323, 4568.657350698678, 4627.074585082997, 343792.0, 344025.0, 344693.0, 344985.0, 345159.0, 59016.0, 59160.0, 59160.40839591549, 59184.40815591789, 59238.70380648069, 39055.80472097678, 39589.60410395859, 39887.401688974685, 42061.36907946383, 42148.57851421508, 18130.728039079426, 18356.449306520826, 18378.0, 18389.632207356044, 18391.90804045998, 13032.869671303166, 13117.868821311855, 13418.865811341762, 13796.241206733524, 13967.092139010932, 19430.022265530017, 20460.260877825553, 20551.897240513703, 20591.79408205929, 20596.691049634268, 7402.7409040683315, 7423.480356375002, 7467.813304667427, 7475.215102414277, 7542.509736867086, 984.7587341101332, 1026.7227848480868, 1029.6396261308632, 1053.8208504554243, 1132.318507017079, 32252.67747322497, 32697.0, 32754.181145471553, 32857.835710821295, 33200.33599328, 1148.3397046698183, 1184.620921305174, 1204.2052245517916, 1208.9697757556132, 1250.6498180509473, 17266.050367229673, 17622.91188544049, 17691.292348306182, 17966.820331796516, 18350.07245290545, 507463.0, 512282.0, 513560.0, 515592.0, 516787.0, 61384.69307653522, 61774.69112654408, 62051.379486205464, 62066.0, 62085.37914620796, 1308.8560258371572, 1322.7486777512242, 1348.2854087333728, 1380.0201856681667, 1395.379056319928, 30205.848970755007, 32294.515582265816, 33501.43841640036, 34114.0, 34459.0, 55855.0, 56727.0, 56802.0, 56861.715691421276, 56956.0, 372662.0, 374667.0, 374772.0, 376386.0, 376682.0, 24801.50396992086, 26926.461470770868, 27365.0, 28984.710152899046, 29038.27404314909, 44717.0, 44748.10503789971, 44946.550534495545, 44962.3255651172, 45266.0, 37816.43275350817, 37947.76473644995, 37951.81024094862, 38917.41623875701, 39239.41140882832, 38739.41890871696, 40517.39223911585, 44599.55400445954, 44770.77614611906, 45723.54276457193, 42463.787681061396, 44955.32567011432, 45064.098718026115, 45639.54360456353, 46258.30612540747, 44037.779811100736, 44746.0, 44953.32570011518, 46123.3081503771, 46511.0, 34512.48231276583, 34952.825235874676, 35625.0, 35711.0, 35718.0], "value": 20885.73028628072, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.60511724096597, 426.12431453363325, 426.1413252296623, 437.2697595016322, 438.8091180336554, 18618.720719189223, 19038.238470461183, 20315.0, 20334.593308133848, 20634.071466783993, 13950.79073813891, 18822.717659235088, 18952.905235473805, 18975.905120474414, 19932.800671993275, 5532.7786888524415, 5625.240592520008, 5796.420357964212, 5804.477597016267, 5889.6171748836405, 5884.735186916578, 5894.292684877821, 5904.61619994699, 5917.970410147965, 5919.082542205943, 5883.852903677421, 5891.0, 5901.232839730829, 5902.203202567663, 5902.439268269529, 5711.942880571182, 5821.039528477796, 5906.911396329058, 5911.615744976584, 5915.73379197935, 5729.828105156852, 5731.598788084836, 5734.111212762028, 5734.856628584277, 5735.627184233012, 13301.0, 18495.907520462446, 23523.411914702097, 23627.763722362728, 23726.169584064646, 2317.849339792925, 2348.823838212124, 2404.2546810488802, 2446.217210492643, 2914.504534229165, 9770.560324785401, 10111.797764044752, 10170.44062576558, 10197.89802101977, 12589.055820813475, 1774.7249176377682, 1919.7600299962505, 1965.3416105604497, 1973.8322242609395, 2016.8890711010888, 78896.0, 83916.0, 84027.0, 85200.0, 86626.0, 10530.842037369446, 10648.361098334124, 10817.621383251548, 11674.416279186007, 12514.937425312815, 17010.234539445755, 17050.783398158408, 17052.14739263032, 17058.829411705974, 17060.402885898933, 8654.524001179869, 8754.430961987451, 8887.289016878634, 8969.686060987835, 8984.685536006209, 8081.515109093473, 8215.589220539068, 8303.211194936472, 8320.958395207985, 8750.606222719993, 4544.272916333371, 4633.096546173464, 4996.825111121166, 5044.545990860766, 5093.363329583802, 1865.7574515312922, 2021.5135383478405, 2103.2112957640884, 2164.664477006035, 2181.694562761215, 882.9602667879832, 903.0425356956022, 972.6060945317158, 975.7365511312048, 1056.4400867540216, 18483.260669573334, 18999.335023274118, 19638.0, 19693.901530492254, 20126.0, 977.3158788847835, 1017.4404077757204, 1022.7238645565656, 1035.4770840725537, 1045.5504133222787, 10555.522226888243, 10926.672199834018, 11144.2756220847, 11298.54805807775, 11423.143264255214, 450582.0, 452627.0, 459185.0, 460508.0, 460924.0, 91840.54079729693, 91996.0, 92164.0, 92179.0, 94206.52896735472, 1001.3191030099457, 1018.5263852308713, 1040.7294103532984, 1043.5982146873357, 1044.8850626431085, 16518.752218716487, 16562.081784386617, 16868.66262674715, 17350.0, 17714.291428342724, 42935.355969661105, 43188.784056079516, 43384.349234762136, 44264.77867610641, 44470.777646111565, 59080.40919590921, 59412.70293648677, 59739.701301493216, 60126.699366502886, 60925.0, 8516.148385161434, 8577.8713319299, 8616.224539791448, 8700.869486957574, 9190.678326258549, 11962.461689223832, 12786.680332991378, 13102.0, 13278.468861245441, 13737.24445155526, 6315.715792789428, 8965.327600430124, 10904.61833835812, 11718.589849355232, 11879.406029698654, 11707.531698731955, 11857.5849845255, 12103.273803571814, 12285.57000504978, 12318.938405307916, 12094.818577721517, 12174.452149653463, 12184.086193535342, 12210.084243681937, 12706.9199118076, 8574.957125214334, 8724.825503490021, 8740.825183496167, 8798.516081615317, 8987.730368088967, 11000.559977600806, 11363.886361136283, 11492.94253528727, 11988.700282493008, 12141.69645758863], "value": 9348.315218727432, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.3475131936677, 1450.4415799917033, 1487.5760408283638, 1643.7616545600888, 1806.5212718629566, 7422.331990120888, 7864.0, 8076.313513351365, 8339.791505212357, 8348.624311905964, 8402.201790829862, 8457.619407126678, 8474.364422668294, 8475.95762021189, 8481.745547633565, 2402.723686776017, 2429.878506074689, 2430.3802530354733, 2431.8662473563945, 2432.9391765205924, 2375.4298968247667, 2411.8914648840846, 2454.6686197363397, 2455.8649274289905, 2461.88921498533, 2383.2373640435067, 2388.1163969331374, 2395.940101497459, 2408.590539608271, 2423.0792298926385, 2336.8714720690355, 2362.444825466014, 2368.39605900496, 2375.5367703297866, 2377.1085842809034, 2345.3315804995664, 2383.475635360218, 2401.6670747735, 2402.5795485789918, 2402.627592723131, 8195.672173113127, 8304.916950830475, 8430.0, 8450.704225352145, 8459.069502354736, 5130.615203859726, 5224.52979231871, 5228.633995620308, 5229.712365819878, 5231.633785635007, 3487.738419618539, 3557.0, 3607.6572725591027, 3641.5448068991377, 3648.7993160376436, 1245.1221888568627, 1256.0705078242036, 1256.9811452828214, 1306.640673814699, 1315.0663029249245, 11363.431828408546, 11497.712557186138, 11525.308481491138, 11528.386025956457, 11534.192606517547, 4165.791710414467, 4508.233600287959, 4593.724376537419, 4666.346711460398, 4678.274867395559, 8932.8660070099, 8936.195742383086, 8941.687040953535, 8944.68693595721, 8947.821043579223, 5114.360704911886, 5148.4079330876675, 5154.252633368141, 5164.638475306692, 5191.143461328914, 4271.124419493996, 4376.037271800199, 4825.0, 4881.267809828554, 4903.583195428358, 3853.075261937171, 3939.4878665773267, 3968.900777480586, 3991.720579559403, 4035.6973227007506, 1303.9608811735663, 1313.9737205255842, 1318.657149141211, 1323.9801402978962, 1325.0592079623382, 924.371427429341, 964.9710508684751, 970.5050424283525, 975.0834215837091, 975.2539307429822, 5640.069388550925, 5683.431656834284, 5838.854028649318, 5851.64890106595, 5904.084866845646, 469.72521075170727, 472.9760069449672, 486.2608834571502, 498.50647858619703, 502.4724039758239, 3812.2375524895133, 4027.9194416111513, 4058.127502586958, 4060.147369052473, 4088.6115818997146, 10078.748031299276, 10352.378857268588, 10458.058774710313, 10463.738406539745, 10479.318844275098, 10354.689359319233, 10359.32664376813, 10368.0, 10382.377057376581, 10403.791924161626, 7756.4182686298755, 7844.333231675258, 7859.371250299962, 7868.0, 7874.291313781672, 5821.650700957956, 5992.820215393545, 6108.144859719731, 6113.755449781959, 6128.938710612837, 9101.544922753968, 9505.239580833379, 9570.33007689455, 9570.56932438028, 9578.856317155387, 9457.243420526203, 9597.088276613711, 9599.616015359308, 9693.854592180982, 9703.12671859536, 6917.619530925847, 7127.429805615642, 7129.429645628441, 7412.592307423143, 7550.924490755242, 7937.2459616336355, 8059.43583949118, 8133.79665508367, 8137.308328791883, 8138.226868447488, 7016.859662806817, 7117.786466406016, 7169.7849064528145, 7182.784516464514, 7352.77941661751, 7135.5361901476235, 7266.4913456059685, 7305.41556675454, 7328.890066649111, 7581.886271705818, 6855.280195579592, 6980.930190698028, 6986.196587392515, 6987.580745155307, 6988.196357418962, 5123.871903202301, 5195.142801437721, 5202.609804264771, 5212.244224587489, 5404.675719456845, 5009.974950125226, 5229.555487783581, 5260.9736951315, 5302.1781623848365, 5411.83764487066], "value": 4483.043010840332, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.8241761536848, 360.3693536311455, 361.9276144771046, 364.20057972749817, 365.661762869346, 5354.41101478837, 5382.89234215316, 5391.245225668409, 5400.488772720684, 5434.646747961379, 5344.251804747339, 5351.170568561869, 5354.785808567663, 5371.221172929932, 5373.387983604923, 3762.6801721853676, 4039.596040395966, 4232.534421213664, 4251.298535741599, 4271.035323751078, 4237.8516751913685, 4259.76571288579, 4270.743755374688, 4271.017665936836, 4297.183535128315, 4226.753107833197, 4243.702940794145, 4269.7224680395675, 4286.849960251392, 4291.485021797389, 4016.678665706751, 4073.918521629551, 4131.194417088669, 4251.958270223784, 4256.553061928515, 3790.033541446913, 3791.0, 3794.5446546414605, 3840.6557704803377, 3845.596212397686, 5150.150225212836, 5172.379314482287, 5221.034108689904, 5227.529522343008, 5262.394824595144, 4772.379590653193, 4785.928211076836, 4817.156997525454, 4830.130576496229, 4841.741147301692, 5519.172124181405, 5522.5581953443625, 5556.194351818964, 5563.582731295129, 5586.553075753971, 4160.604742549453, 4170.270202714512, 4188.7905604719945, 4197.517285512145, 4212.936805947883, 5549.861253468655, 5789.507891829199, 5790.594658373873, 5811.418858114197, 5857.794977175821, 4189.518205406356, 4214.894627634334, 4220.767857767853, 4220.894477638022, 4225.429567008415, 4276.310857211384, 4288.31386978082, 4306.569343065668, 4314.633256173198, 4330.133973205308, 4762.214234651313, 4769.642276829251, 4803.967147063398, 4865.659403841697, 4885.972321486614, 5590.636608620427, 5594.24477695514, 5598.860028499239, 5623.297087864017, 5643.802466913638, 1399.9580012599638, 1422.4665750343622, 1425.5673048586311, 1438.467766926244, 1440.2582669924998, 975.7902051059057, 990.9554070066864, 1007.1942446043282, 1017.8167929772712, 1025.8409946458312, 5276.762545685453, 5284.286621306074, 5303.920441193385, 5347.491988261109, 5439.809606663748, 5560.2493663355735, 5629.211910332557, 5691.089425691869, 5700.543956483471, 5713.8285851424525, 5240.528352448222, 5273.34083239595, 5289.2859463972645, 5495.450454954473, 5541.722913854372, 2948.3047247832155, 2964.214483161964, 2968.6586042604945, 2975.285931376454, 2996.7602591792606, 5697.9430205698245, 5803.564732645069, 5902.203202567598, 5940.31686356066, 5955.34491205967, 5607.710226647812, 5671.234383358194, 5679.375268720438, 5717.2281741965135, 5748.856278593069, 5865.912011319833, 6005.939940600625, 6006.639601623917, 6008.128821320972, 6022.45797878193, 5781.884362312814, 5807.883842323045, 5809.5352371811, 5811.912821307768, 5819.592628516132, 5539.612227144061, 5668.37647858727, 5687.516561092353, 5690.914636280542, 5713.314402271754, 5916.467517923407, 5970.5223582114195, 5971.8354920790025, 5972.552058595709, 6019.18740969981, 4040.8383664653084, 4211.494620645542, 4236.3857240700545, 4285.3571964204375, 4302.376155457504, 5725.341585717696, 5730.656160630376, 5734.168545560787, 5737.512311453574, 5764.827055188351, 5642.0, 5643.181738647791, 5646.435356464321, 5674.460926212003, 5674.4892959633835, 5420.4037555868035, 5607.682194684327, 5636.577256705846, 5638.097904335286, 5672.914906276326, 5673.205751194919, 5680.176374425768, 5680.829575112753, 5691.857703557444, 5694.259746232963, 5594.188842617879, 5734.483896449173, 5736.168255602831, 5743.511801496752, 5747.8563035924435, 5523.585731070106, 5565.944340556704, 5577.0, 5586.832395028156, 5593.9233421618765], "value": 4320.756060349284, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.4269240761835, 779.1624004195489, 807.676929228309, 813.4346629092782, 863.594110767939, 14439.63900902478, 14772.556823295305, 15136.545903622882, 15256.771148432754, 15468.535943921686, 13142.211467311969, 14071.08537945033, 14201.360938757754, 14212.644683882909, 14227.573172804807, 792.3344390711816, 829.1169904052165, 830.9459885107478, 831.4637059096879, 831.6216121664659, 821.1993306526119, 823.7816978500703, 823.8887750153713, 824.114077366833, 824.196408501709, 866.6663334616187, 868.7784614923186, 869.0483920107472, 869.0483920107472, 869.4696235296464, 858.716623514242, 859.2438653984516, 862.9482231066124, 863.8056437301557, 863.8531449653542, 628.5631486117151, 630.8738252349549, 632.7658766256468, 633.1262857256955, 633.1484153813115, 13401.396937137852, 13656.180629162282, 13728.039037267396, 14866.182359970197, 15029.774553381709, 14565.854341456556, 14737.0, 15034.774478382833, 15371.461998829987, 15557.922210388875, 11923.403829808562, 11925.165238433314, 11946.761064778655, 12026.939865300617, 12179.817302740466, 3870.0, 3893.7274390792654, 3942.0539070622845, 3989.940150897739, 3994.0414300567945, 65138.0, 68140.0, 68336.3166368335, 69005.3099469004, 69237.0, 10376.844347334796, 10873.347599144077, 10969.286996345212, 11107.111431085494, 11387.08903287735, 15470.0, 15843.683126337639, 20426.489337766674, 20507.58984820295, 20615.278465253945, 6382.84042898922, 7640.885386719204, 7908.604569771488, 8213.507189568645, 8598.5700714964, 4601.539846015372, 4627.953720462752, 4725.0, 4728.196206644879, 4741.762911854394, 3476.652334766503, 3558.964410355915, 3560.9821950890077, 3563.073600863794, 3600.4419315006216, 634.6921742954663, 645.6416688737795, 653.5294587896707, 660.5640277416882, 669.6149713914518, 716.2372073741553, 747.8130467383154, 749.6326799868025, 795.2961628958413, 809.5587904592012, 9227.123423274777, 9557.948625651174, 9834.114929656365, 10172.847407288897, 10242.129418999468, 685.9416949559244, 782.3701919954491, 784.4822417204732, 791.6318911705969, 795.6976348987348, 4769.308450274691, 4834.444038935497, 4839.322494850724, 4839.346688197048, 4880.0, 57137.71431142818, 58181.0, 58842.70578647165, 94793.52603237078, 97599.51200243953, 50733.23900141501, 68370.0, 82520.0, 84664.15335846563, 85758.14241857626, 76631.0, 76814.0, 77785.0, 78404.60797695976, 79688.0, 9086.772830679021, 9480.336376453582, 9542.761430964281, 9570.56932438028, 9593.808123837345, 49388.25917611167, 50816.491835082656, 51090.0, 51269.487305127965, 51657.483425165265, 40694.1861162781, 62114.37885621086, 68422.65788671025, 69131.65434172998, 70944.29055709377, 11199.88800112021, 11374.829377559177, 11651.951846018768, 11753.47109380097, 11947.880521194676, 13297.468101275841, 13322.666933326744, 13507.864921351054, 13595.86404135946, 13728.107673001223, 12190.939045305071, 12358.196717213352, 12492.812607811073, 13147.079704420981, 13172.73654526885, 12218.572349967708, 12292.692682683004, 12451.43968521437, 12816.102872799187, 13167.67080822987, 12384.380780961095, 12398.752024959269, 12432.875671243533, 13341.466341346626, 13370.197788132744, 10361.63734269297, 10450.059494645518, 10735.0, 10850.45747712627, 11126.332420054823, 10869.945650271698, 10982.835257470984, 11165.441727913734, 11192.38441885704, 11272.605458808903], "value": 7231.970063059502, "unit": "score"}], "name": "dromaeo_css", "value": 7313.477686385527, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 109.0, 110.0, 112.0, 109.0, 110.0, 111.0, 110.0, 110.0], "value": 109.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 118.0, 117.0, 157.0, 122.0, 118.0, 124.0, 116.0, 151.0, 118.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 159.0, 160.0, 161.0, 159.0, 160.0, 159.0, 159.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 122.0, 75.0, 76.0, 74.0, 75.0, 107.0, 74.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 105.0, 82.0, 82.0, 82.0, 104.0, 82.0, 81.0, 108.0], "value": 88.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 147.0, 146.0, 145.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 85.0, 84.0, 85.0, 84.0, 85.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 52.0, 51.0, 52.0, 52.0, 53.0, 51.0, 51.0, 52.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 57.0, 58.0, 59.0, 58.0, 58.0, 62.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 73.0, 78.0, 80.0, 75.0, 74.0, 74.0, 74.0, 75.0, 75.0], "value": 75.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 121.0, 125.0, 130.0, 128.0, 122.0, 119.0, 119.0, 157.0, 120.0], "value": 126.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 153.0, 153.0, 151.0, 152.0, 154.0, 155.0, 150.0, 154.0, 152.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 61.0, 64.0, 62.0, 63.0, 67.0, 62.0, 60.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1444.6, "alertThreshold": 2.0}]} 14:02:03 INFO - Return code: 0 14:02:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:02:03 INFO - # TBPL SUCCESS # 14:02:03 INFO - Running post-action listener: _resource_record_post_action 14:02:03 INFO - [mozharness: 2017-01-20 22:02:03.966058Z] Finished run-tests step (success) 14:02:03 INFO - Running post-run listener: _resource_record_post_run 14:02:04 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 346886144; Read time: 0; Write time: 197680 14:02:04 INFO - TinderboxPrint: CPU usage
12.9% 14:02:04 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:02:04 INFO - TinderboxPrint: I/O write bytes / time
346,886,144 / 197,680 14:02:04 INFO - TinderboxPrint: CPU idle
8,267.5 (87.0%) 14:02:04 INFO - TinderboxPrint: CPU user
1,189.4 (12.5%) 14:02:04 INFO - TinderboxPrint: Swap in / out
0 / 0 14:02:04 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107732992; Read time: 0; Write time: 77228 14:02:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 238166016; Read time: 0; Write time: 120432 14:02:04 INFO - Running post-run listener: _upload_blobber_files 14:02:04 INFO - Blob upload gear active. 14:02:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:02:04 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:02:04 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:02:04 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:02:05 INFO - (blobuploader) - INFO - Open directory for files ... 14:02:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:02:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:02:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:02:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:02:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:02:05 INFO - (blobuploader) - INFO - Done attempting. 14:02:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:02:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:02:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:02:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:02:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:02:09 INFO - (blobuploader) - INFO - Done attempting. 14:02:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:02:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:02:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:02:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:02:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:02:09 INFO - (blobuploader) - INFO - Done attempting. 14:02:09 INFO - (blobuploader) - INFO - Iteration through files over. 14:02:09 INFO - Return code: 0 14:02:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:02:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:02:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecac0ce074fd528a35aeef746fd4255cf808fc86fdd8a66bdafe6e4e9893393992f7ef79477fdd054fbf10f7ba5f7a8e05cba7c437b2b85eec004a38f77d82ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a25bda9624915e6f6569afa9be8992197381c5ebc266ab2ecc44507fa196b1278942b713ad50d00c3d48fdc278a65fea3318d17ae7aec21689198fe6182369b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a05ed199ed7a22960c82f9423d62059c3c43186483bffa1a551b09bb2e656814d310028371cb2f652ee0e9af158858ba15b15550d0534da4c1a74dda3302a08"} 14:02:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:02:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:02:09 INFO - Contents: 14:02:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecac0ce074fd528a35aeef746fd4255cf808fc86fdd8a66bdafe6e4e9893393992f7ef79477fdd054fbf10f7ba5f7a8e05cba7c437b2b85eec004a38f77d82ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a25bda9624915e6f6569afa9be8992197381c5ebc266ab2ecc44507fa196b1278942b713ad50d00c3d48fdc278a65fea3318d17ae7aec21689198fe6182369b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a05ed199ed7a22960c82f9423d62059c3c43186483bffa1a551b09bb2e656814d310028371cb2f652ee0e9af158858ba15b15550d0534da4c1a74dda3302a08"} 14:02:09 INFO - Running post-run listener: copy_logs_to_upload_dir 14:02:09 INFO - Copying logs to upload dir... 14:02:09 INFO - mkdir: /builds/slave/test/build/upload/logs 14:02:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.526527 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2017-01-20 14:02:09.508566) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 14:02:09.513961) ========= 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=ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecac0ce074fd528a35aeef746fd4255cf808fc86fdd8a66bdafe6e4e9893393992f7ef79477fdd054fbf10f7ba5f7a8e05cba7c437b2b85eec004a38f77d82ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a25bda9624915e6f6569afa9be8992197381c5ebc266ab2ecc44507fa196b1278942b713ad50d00c3d48fdc278a65fea3318d17ae7aec21689198fe6182369b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a05ed199ed7a22960c82f9423d62059c3c43186483bffa1a551b09bb2e656814d310028371cb2f652ee0e9af158858ba15b15550d0534da4c1a74dda3302a08"} build_url:https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011397 build_url: 'https://queue.taskcluster.net/v1/task/Mzu7IRU3RO6fGXbLyrUoHg/artifacts/public/build/firefox-52.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ecac0ce074fd528a35aeef746fd4255cf808fc86fdd8a66bdafe6e4e9893393992f7ef79477fdd054fbf10f7ba5f7a8e05cba7c437b2b85eec004a38f77d82ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a25bda9624915e6f6569afa9be8992197381c5ebc266ab2ecc44507fa196b1278942b713ad50d00c3d48fdc278a65fea3318d17ae7aec21689198fe6182369b1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2a05ed199ed7a22960c82f9423d62059c3c43186483bffa1a551b09bb2e656814d310028371cb2f652ee0e9af158858ba15b15550d0534da4c1a74dda3302a08"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-20 14:02:09.538803) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 14:02:09.539114) ========= 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=ed14279e38b05397cf4aa391000001a4-1484948494.12096-1505960478 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004473 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-20 14:02:09.621562) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 14:02:09.621916) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-20 14:02:09.622217) ========= ========= Total master_lag: 0.16 =========