builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-033 starttime: 1483059966.72 results: success (0) buildid: 20161229153015 builduid: 720fb7e0af4243e9ad33cb2ae4e3eea9 revision: 72e5976fb87ae161a9be1726e2311c6eba40278a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.723042) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.723519) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.723838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.768487) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.768789) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-29 17:06:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 87.8M=0s 2016-12-29 17:06:07 (87.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.196239 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.980487) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:06.980994) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014389 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.032782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.033356) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 72e5976fb87ae161a9be1726e2311c6eba40278a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 72e5976fb87ae161a9be1726e2311c6eba40278a --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=edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-29 17:06:07,296 truncating revision to first 12 chars 2016-12-29 17:06:07,296 Setting DEBUG logging. 2016-12-29 17:06:07,297 attempt 1/10 2016-12-29 17:06:07,297 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/72e5976fb87a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-29 17:06:07,606 unpacking tar archive at: mozilla-inbound-72e5976fb87a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632393 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.679502) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.679853) ========= script_repo_revision: 72e5976fb87ae161a9be1726e2311c6eba40278a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.680620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.680929) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-29 17:06:07.689716) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-12-29 17:06:07.690018) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:06:08 INFO - MultiFileLogger online at 20161229 17:06:08 in /builds/slave/test 17:06:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 17:06:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:06:08 INFO - {'append_to_log': False, 17:06:08 INFO - 'base_work_dir': '/builds/slave/test', 17:06:08 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 17:06:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:06:08 INFO - 'branch': 'Mozilla-Inbound', 17:06:08 INFO - 'buildbot_json_path': 'buildprops.json', 17:06:08 INFO - 'config_files': ('talos/linux_config.py',), 17:06:08 INFO - 'default_actions': ('clobber', 17:06:08 INFO - 'read-buildbot-config', 17:06:08 INFO - 'download-and-extract', 17:06:08 INFO - 'populate-webroot', 17:06:08 INFO - 'create-virtualenv', 17:06:08 INFO - 'install', 17:06:08 INFO - 'run-tests'), 17:06:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:06:08 INFO - 'download_minidump_stackwalk': True, 17:06:08 INFO - 'download_symbols': 'ondemand', 17:06:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:06:08 INFO - 'tooltool.py': '/tools/tooltool.py', 17:06:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:06:08 INFO - '/tools/misc-python/virtualenv.py')}, 17:06:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:06:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:06:08 INFO - 'installer_path': 'installer.exe', 17:06:08 INFO - 'log_level': 'info', 17:06:08 INFO - 'log_name': 'talos', 17:06:08 INFO - 'log_to_console': True, 17:06:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:06:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:06:08 INFO - 'opt_config_files': (), 17:06:08 INFO - 'pip_index': False, 17:06:08 INFO - 'sps_profile': False, 17:06:08 INFO - 'sps_profile_interval': 0, 17:06:08 INFO - 'suite': 'dromaeojs-e10s', 17:06:08 INFO - 'system_bits': '32', 17:06:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:06:08 INFO - 'title': 'talos-linux64-ix-033', 17:06:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:06:08 INFO - 'use_talos_json': True, 17:06:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:06:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:06:08 INFO - 'work_dir': 'build'} 17:06:08 INFO - [mozharness: 2016-12-30 01:06:08.047638Z] Running clobber step. 17:06:08 INFO - Running pre-action listener: _resource_record_pre_action 17:06:08 INFO - Running main action method: clobber 17:06:08 INFO - rmtree: /builds/slave/test/build 17:06:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:06:09 INFO - Running post-action listener: _resource_record_post_action 17:06:09 INFO - [mozharness: 2016-12-30 01:06:09.030433Z] Finished clobber step (success) 17:06:09 INFO - [mozharness: 2016-12-30 01:06:09.030533Z] Running read-buildbot-config step. 17:06:09 INFO - Running pre-action listener: _resource_record_pre_action 17:06:09 INFO - Running main action method: read_buildbot_config 17:06:09 INFO - Using buildbot properties: 17:06:09 INFO - { 17:06:09 INFO - "project": "", 17:06:09 INFO - "product": "firefox", 17:06:09 INFO - "script_repo_revision": "production", 17:06:09 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs-e10s", 17:06:09 INFO - "repository": "", 17:06:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs-e10s", 17:06:09 INFO - "buildid": "20161229153015", 17:06:09 INFO - "pgo_build": "True", 17:06:09 INFO - "basedir": "/builds/slave/test", 17:06:09 INFO - "buildnumber": 2374, 17:06:09 INFO - "slavename": "talos-linux64-ix-033", 17:06:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:06:09 INFO - "platform": "ubuntu64_hw", 17:06:09 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 17:06:09 INFO - "branch": "mozilla-inbound", 17:06:09 INFO - "repo_path": "integration/mozilla-inbound", 17:06:09 INFO - "revision": "72e5976fb87ae161a9be1726e2311c6eba40278a", 17:06:09 INFO - "stage_platform": "linux64-pgo", 17:06:09 INFO - "builduid": "720fb7e0af4243e9ad33cb2ae4e3eea9", 17:06:09 INFO - "slavebuilddir": "test" 17:06:09 INFO - } 17:06:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 17:06:09 INFO - Running post-action listener: _resource_record_post_action 17:06:09 INFO - [mozharness: 2016-12-30 01:06:09.032965Z] Finished read-buildbot-config step (success) 17:06:09 INFO - [mozharness: 2016-12-30 01:06:09.033056Z] Running download-and-extract step. 17:06:09 INFO - Running pre-action listener: _resource_record_pre_action 17:06:09 INFO - Running main action method: download_and_extract 17:06:09 INFO - mkdir: /builds/slave/test/build/tests 17:06:09 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')]} 17:06:09 INFO - trying https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:06:09 INFO - Downloading https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:06:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:06:09 INFO - Downloaded 1361 bytes. 17:06:09 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 17:06:09 INFO - Using the following test package requirements: 17:06:09 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:09 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 17:06:09 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 17:06:09 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'jsshell-linux-x86_64.zip'], 17:06:09 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 17:06:09 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 17:06:09 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 17:06:09 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 17:06:09 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 17:06:09 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 17:06:09 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 17:06:09 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:06:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:06:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:06:09 INFO - Fetch https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:06:10 INFO - Expected file size: 21834725 17:06:10 INFO - Obtained file size: 21834725 17:06:14 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:06:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 17:06:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:06:14 INFO - Fetch https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 17:06:15 INFO - Expected file size: 21834725 17:06:15 INFO - Obtained file size: 21834725 17:06:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 17:06:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:06:19 INFO - Fetch https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 17:06:20 INFO - Expected file size: 14409776 17:06:20 INFO - Obtained file size: 14409776 17:06:20 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')]} 17:06:20 INFO - trying https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:06:20 INFO - Downloading https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:06:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:06:21 INFO - Downloaded 61374120 bytes. 17:06:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:06:21 INFO - mkdir: /builds/slave/test/properties 17:06:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:06:21 INFO - Writing to file /builds/slave/test/properties/build_url 17:06:21 INFO - Contents: 17:06:21 INFO - build_url:https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 17:06:23 INFO - Running post-action listener: _resource_record_post_action 17:06:23 INFO - Running post-action listener: set_extra_try_arguments 17:06:23 INFO - [mozharness: 2016-12-30 01:06:23.298365Z] Finished download-and-extract step (success) 17:06:23 INFO - [mozharness: 2016-12-30 01:06:23.298595Z] Running populate-webroot step. 17:06:23 INFO - Running pre-action listener: _resource_record_pre_action 17:06:23 INFO - Running main action method: populate_webroot 17:06:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:06:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:06:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:06:23 INFO - '--tppagecycles', 17:06:23 INFO - '7'], 17:06:23 INFO - 'tests': ['tsvgm']}}, 17:06:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:06:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:06:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:06:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:06:23 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:23 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:06:23 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['damp', 'tps']}, 17:06:23 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'tests': ['damp', 'tps']}, 17:06:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['dromaeo_dom']}, 17:06:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:06:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['basic_compositor_video']}, 17:06:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:06:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['a11yr', 17:06:23 INFO - 'ts_paint', 17:06:23 INFO - 'tpaint', 17:06:23 INFO - 'sessionrestore', 17:06:23 INFO - 'sessionrestore_no_auto_restore', 17:06:23 INFO - 'tabpaint']}, 17:06:23 INFO - 'other-e10s': {'tests': ['a11yr', 17:06:23 INFO - 'ts_paint', 17:06:23 INFO - 'tpaint', 17:06:23 INFO - 'sessionrestore', 17:06:23 INFO - 'sessionrestore_no_auto_restore', 17:06:23 INFO - 'tabpaint']}, 17:06:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:06:23 INFO - 'ts_paint', 17:06:23 INFO - 'tpaint', 17:06:23 INFO - 'sessionrestore', 17:06:23 INFO - 'sessionrestore_no_auto_restore', 17:06:23 INFO - 'tabpaint']}, 17:06:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:06:23 INFO - 'ts_paint', 17:06:23 INFO - 'tpaint', 17:06:23 INFO - 'sessionrestore', 17:06:23 INFO - 'sessionrestore_no_auto_restore', 17:06:23 INFO - 'tabpaint']}, 17:06:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['a11yr', 17:06:23 INFO - 'ts_paint', 17:06:23 INFO - 'tpaint', 17:06:23 INFO - 'sessionrestore', 17:06:23 INFO - 'sessionrestore_no_auto_restore', 17:06:23 INFO - 'tabpaint']}, 17:06:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['a11yr', 17:06:23 INFO - 'ts_paint', 17:06:23 INFO - 'tpaint', 17:06:23 INFO - 'sessionrestore', 17:06:23 INFO - 'sessionrestore_no_auto_restore', 17:06:23 INFO - 'tabpaint']}, 17:06:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['tsvgx', 17:06:23 INFO - 'tsvgr_opacity', 17:06:23 INFO - 'tart', 17:06:23 INFO - 'tscrollx', 17:06:23 INFO - 'cart', 17:06:23 INFO - 'tsvg_static']}, 17:06:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:06:23 INFO - 'tsvgr_opacity', 17:06:23 INFO - 'tart', 17:06:23 INFO - 'tscrollx', 17:06:23 INFO - 'cart', 17:06:23 INFO - 'tsvg_static']}, 17:06:23 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'talos_options': ['--disable-e10s'], 17:06:23 INFO - 'tests': ['tp5o']}, 17:06:23 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:06:23 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'talos_options': ['--disable-e10s', 17:06:23 INFO - '--xperf_path', 17:06:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:23 INFO - 'tests': ['tp5n']}, 17:06:23 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:06:23 INFO - 'talos_options': ['--xperf_path', 17:06:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:06:23 INFO - 'tests': ['tp5n']}}, 17:06:23 INFO - 'talos.zip': {'path': '', 17:06:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:06:23 INFO - Running post-action listener: _resource_record_post_action 17:06:23 INFO - [mozharness: 2016-12-30 01:06:23.316432Z] Finished populate-webroot step (success) 17:06:23 INFO - [mozharness: 2016-12-30 01:06:23.316521Z] Running create-virtualenv step. 17:06:23 INFO - Running pre-action listener: _resource_record_pre_action 17:06:23 INFO - Running main action method: create_virtualenv 17:06:23 INFO - Creating virtualenv /builds/slave/test/build/venv 17:06:23 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 17:06:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:06:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:06:23 INFO - Using real prefix '/usr' 17:06:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:06:23 INFO - Installing distribute.............................................................................................................................................................................................done. 17:06:25 INFO - Installing pip.................done. 17:06:25 INFO - Return code: 0 17:06:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:06:26 INFO - Reading from file tmpfile_stdout 17:06:26 INFO - Output received: 17:06:26 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) 17:06:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:06:26 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')]} 17:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:26 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')]} 17:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:26 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:26 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 17:06:26 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 17:06:26 INFO - Using env: {'DISPLAY': ':0', 17:06:26 INFO - 'HOME': '/home/cltbld', 17:06:26 INFO - 'LANG': 'en_US.UTF-8', 17:06:26 INFO - 'LANGUAGE': 'en_US:en', 17:06:26 INFO - 'LOGNAME': 'cltbld', 17:06:26 INFO - 'MAIL': '/var/mail/cltbld', 17:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:26 INFO - 'MOZ_NO_REMOTE': '1', 17:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:26 INFO - 'NO_EM_RESTART': '1', 17:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:26 INFO - 'PWD': '/builds/slave/test', 17:06:26 INFO - 'SHELL': '/bin/bash', 17:06:26 INFO - 'SHLVL': '1', 17:06:26 INFO - 'TERM': 'linux', 17:06:26 INFO - 'TMOUT': '86400', 17:06:26 INFO - 'USER': 'cltbld', 17:06:26 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:26 INFO - '_': '/tools/buildbot/bin/python'} 17:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:26 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 17:06:26 INFO - Cleaning up... 17:06:26 INFO - Return code: 0 17:06:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:06:26 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')]} 17:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:26 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')]} 17:06:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:26 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:26 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 17:06:26 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 17:06:26 INFO - Using env: {'DISPLAY': ':0', 17:06:26 INFO - 'HOME': '/home/cltbld', 17:06:26 INFO - 'LANG': 'en_US.UTF-8', 17:06:26 INFO - 'LANGUAGE': 'en_US:en', 17:06:26 INFO - 'LOGNAME': 'cltbld', 17:06:26 INFO - 'MAIL': '/var/mail/cltbld', 17:06:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:26 INFO - 'MOZ_NO_REMOTE': '1', 17:06:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:26 INFO - 'NO_EM_RESTART': '1', 17:06:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:26 INFO - 'PWD': '/builds/slave/test', 17:06:26 INFO - 'SHELL': '/bin/bash', 17:06:26 INFO - 'SHLVL': '1', 17:06:26 INFO - 'TERM': 'linux', 17:06:26 INFO - 'TMOUT': '86400', 17:06:26 INFO - 'USER': 'cltbld', 17:06:26 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:26 INFO - '_': '/tools/buildbot/bin/python'} 17:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:26 INFO - Downloading/unpacking psutil>=3.1.1 17:06:26 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 17:06:26 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 17:06:26 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 17:06:26 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 17:06:26 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 17:06:26 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 17:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:06:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:28 INFO - Installing collected packages: psutil 17:06:28 INFO - Running setup.py install for psutil 17:06:28 INFO - building 'psutil._psutil_linux' extension 17:06:28 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 17:06:28 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 17:06:28 INFO - building 'psutil._psutil_posix' extension 17:06:28 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 17:06:29 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 17:06:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:06:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:06:29 INFO - Successfully installed psutil 17:06:29 INFO - Cleaning up... 17:06:29 INFO - Return code: 0 17:06:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:06: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')]} 17:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06: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')]} 17:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:29 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:29 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 17:06:29 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 17:06:29 INFO - Using env: {'DISPLAY': ':0', 17:06:29 INFO - 'HOME': '/home/cltbld', 17:06:29 INFO - 'LANG': 'en_US.UTF-8', 17:06:29 INFO - 'LANGUAGE': 'en_US:en', 17:06:29 INFO - 'LOGNAME': 'cltbld', 17:06:29 INFO - 'MAIL': '/var/mail/cltbld', 17:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:29 INFO - 'MOZ_NO_REMOTE': '1', 17:06:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:29 INFO - 'NO_EM_RESTART': '1', 17:06:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:29 INFO - 'PWD': '/builds/slave/test', 17:06:29 INFO - 'SHELL': '/bin/bash', 17:06:29 INFO - 'SHLVL': '1', 17:06:29 INFO - 'TERM': 'linux', 17:06:29 INFO - 'TMOUT': '86400', 17:06:29 INFO - 'USER': 'cltbld', 17:06:29 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:29 INFO - '_': '/tools/buildbot/bin/python'} 17:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:06:29 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 17:06:29 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 17:06:29 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 17:06:29 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 17:06:29 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 17:06:29 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 17:06:31 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:06:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:06:31 INFO - Installing collected packages: mozsystemmonitor 17:06:31 INFO - Running setup.py install for mozsystemmonitor 17:06:31 INFO - Successfully installed mozsystemmonitor 17:06:31 INFO - Cleaning up... 17:06:31 INFO - Return code: 0 17:06:31 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:06:31 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')]} 17:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:31 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')]} 17:06:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:31 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:31 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 17:06:31 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 17:06:31 INFO - Using env: {'DISPLAY': ':0', 17:06:31 INFO - 'HOME': '/home/cltbld', 17:06:31 INFO - 'LANG': 'en_US.UTF-8', 17:06:31 INFO - 'LANGUAGE': 'en_US:en', 17:06:31 INFO - 'LOGNAME': 'cltbld', 17:06:31 INFO - 'MAIL': '/var/mail/cltbld', 17:06:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:31 INFO - 'MOZ_NO_REMOTE': '1', 17:06:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:31 INFO - 'NO_EM_RESTART': '1', 17:06:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:31 INFO - 'PWD': '/builds/slave/test', 17:06:31 INFO - 'SHELL': '/bin/bash', 17:06:31 INFO - 'SHLVL': '1', 17:06:31 INFO - 'TERM': 'linux', 17:06:31 INFO - 'TMOUT': '86400', 17:06:31 INFO - 'USER': 'cltbld', 17:06:31 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:31 INFO - '_': '/tools/buildbot/bin/python'} 17:06:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:31 INFO - Downloading/unpacking jsonschema==2.5.1 17:06:31 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 17:06:31 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 17:06:31 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 17:06:31 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 17:06:31 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 17:06:31 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 17:06:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:06:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:06:33 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 17:06:33 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 17:06:33 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 17:06:33 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 17:06:33 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 17:06:33 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 17:06:34 INFO - Downloading functools32-3.2.3-2.tar.gz 17:06:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:06:34 INFO - warning: no files found matching '*.txt' 17:06:34 INFO - no previously-included directories found matching 'build' 17:06:34 INFO - no previously-included directories found matching 'dist' 17:06:34 INFO - no previously-included directories found matching '.git*' 17:06:34 INFO - Installing collected packages: jsonschema, functools32 17:06:34 INFO - Running setup.py install for functools32 17:06:34 INFO - warning: no files found matching '*.txt' 17:06:34 INFO - no previously-included directories found matching 'build' 17:06:34 INFO - no previously-included directories found matching 'dist' 17:06:34 INFO - no previously-included directories found matching '.git*' 17:06:34 INFO - Successfully installed jsonschema functools32 17:06:34 INFO - Cleaning up... 17:06:34 INFO - Return code: 0 17:06:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:06:34 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')]} 17:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:34 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')]} 17:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:34 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:34 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 17:06:34 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 17:06:34 INFO - Using env: {'DISPLAY': ':0', 17:06:34 INFO - 'HOME': '/home/cltbld', 17:06:34 INFO - 'LANG': 'en_US.UTF-8', 17:06:34 INFO - 'LANGUAGE': 'en_US:en', 17:06:34 INFO - 'LOGNAME': 'cltbld', 17:06:34 INFO - 'MAIL': '/var/mail/cltbld', 17:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:34 INFO - 'MOZ_NO_REMOTE': '1', 17:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:34 INFO - 'NO_EM_RESTART': '1', 17:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:34 INFO - 'PWD': '/builds/slave/test', 17:06:34 INFO - 'SHELL': '/bin/bash', 17:06:34 INFO - 'SHLVL': '1', 17:06:34 INFO - 'TERM': 'linux', 17:06:34 INFO - 'TMOUT': '86400', 17:06:34 INFO - 'USER': 'cltbld', 17:06:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:34 INFO - '_': '/tools/buildbot/bin/python'} 17:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:06:34 INFO - Cleaning up... 17:06:34 INFO - Return code: 0 17:06:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:06:34 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')]} 17:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:34 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')]} 17:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:34 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:34 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 17:06:34 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 17:06:34 INFO - Using env: {'DISPLAY': ':0', 17:06:34 INFO - 'HOME': '/home/cltbld', 17:06:34 INFO - 'LANG': 'en_US.UTF-8', 17:06:34 INFO - 'LANGUAGE': 'en_US:en', 17:06:34 INFO - 'LOGNAME': 'cltbld', 17:06:34 INFO - 'MAIL': '/var/mail/cltbld', 17:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:34 INFO - 'MOZ_NO_REMOTE': '1', 17:06:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:34 INFO - 'NO_EM_RESTART': '1', 17:06:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:34 INFO - 'PWD': '/builds/slave/test', 17:06:34 INFO - 'SHELL': '/bin/bash', 17:06:34 INFO - 'SHLVL': '1', 17:06:34 INFO - 'TERM': 'linux', 17:06:34 INFO - 'TMOUT': '86400', 17:06:34 INFO - 'USER': 'cltbld', 17:06:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:34 INFO - '_': '/tools/buildbot/bin/python'} 17:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:34 INFO - Downloading/unpacking blobuploader==1.2.4 17:06:34 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 17:06:34 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 17:06:34 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 17:06:34 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 17:06:34 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 17:06:34 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 17:06:36 INFO - Downloading blobuploader-1.2.4.tar.gz 17:06:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:06:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:06:36 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 17:06:36 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 17:06:36 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 17:06:36 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 17:06:36 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 17:06:36 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 17:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:06:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 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 17:06:37 INFO - Downloading docopt-0.6.1.tar.gz 17:06:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:06:37 INFO - Installing collected packages: blobuploader, requests, docopt 17:06:37 INFO - Running setup.py install for blobuploader 17:06:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:06:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:06:37 INFO - Running setup.py install for requests 17:06:38 INFO - Running setup.py install for docopt 17:06:38 INFO - Successfully installed blobuploader requests docopt 17:06:38 INFO - Cleaning up... 17:06:38 INFO - Return code: 0 17:06:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:38 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')]} 17:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:38 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')]} 17:06:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:38 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:38 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 17:06:38 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 17:06:38 INFO - Using env: {'DISPLAY': ':0', 17:06:38 INFO - 'HOME': '/home/cltbld', 17:06:38 INFO - 'LANG': 'en_US.UTF-8', 17:06:38 INFO - 'LANGUAGE': 'en_US:en', 17:06:38 INFO - 'LOGNAME': 'cltbld', 17:06:38 INFO - 'MAIL': '/var/mail/cltbld', 17:06:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:38 INFO - 'MOZ_NO_REMOTE': '1', 17:06:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:38 INFO - 'NO_EM_RESTART': '1', 17:06:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:38 INFO - 'PWD': '/builds/slave/test', 17:06:38 INFO - 'SHELL': '/bin/bash', 17:06:38 INFO - 'SHLVL': '1', 17:06:38 INFO - 'TERM': 'linux', 17:06:38 INFO - 'TMOUT': '86400', 17:06:38 INFO - 'USER': 'cltbld', 17:06:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:38 INFO - '_': '/tools/buildbot/bin/python'} 17:06:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:38 INFO - Running setup.py (path:/tmp/pip-gV8SQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:38 INFO - Running setup.py (path:/tmp/pip-0llafz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:38 INFO - Running setup.py (path:/tmp/pip-bCkZJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:38 INFO - Running setup.py (path:/tmp/pip-zJCrnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:38 INFO - Running setup.py (path:/tmp/pip-TdhiJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:38 INFO - Running setup.py (path:/tmp/pip-yjkNtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:38 INFO - Running setup.py (path:/tmp/pip-h7U1MX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:38 INFO - Running setup.py (path:/tmp/pip-z9ijuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:39 INFO - Running setup.py (path:/tmp/pip-VDELpe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:39 INFO - Running setup.py (path:/tmp/pip-AchFca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:39 INFO - Running setup.py (path:/tmp/pip-kpEKWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:39 INFO - Running setup.py (path:/tmp/pip-TxUG8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:39 INFO - Running setup.py (path:/tmp/pip-PUOqGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:39 INFO - Running setup.py (path:/tmp/pip-PQ385u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:39 INFO - Running setup.py (path:/tmp/pip-xoXKuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:39 INFO - Running setup.py (path:/tmp/pip-dLhQBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:39 INFO - Running setup.py (path:/tmp/pip-iD8DH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:06:39 INFO - Running setup.py install for manifestparser 17:06:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:06:39 INFO - Running setup.py install for mozcrash 17:06:39 INFO - Running setup.py install for mozdebug 17:06:39 INFO - Running setup.py install for mozdevice 17:06:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozfile 17:06:40 INFO - Running setup.py install for mozhttpd 17:06:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozinfo 17:06:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozInstall 17:06:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozleak 17:06:40 INFO - Running setup.py install for mozlog 17:06:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for moznetwork 17:06:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Running setup.py install for mozprocess 17:06:40 INFO - Running setup.py install for mozprofile 17:06:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:06:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozrunner 17:06:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Running setup.py install for mozscreenshot 17:06:41 INFO - Running setup.py install for moztest 17:06:41 INFO - Running setup.py install for mozversion 17:06:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:06:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:06:41 INFO - Cleaning up... 17:06:41 INFO - Return code: 0 17:06:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:41 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')]} 17:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:41 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')]} 17:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:41 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:41 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 17:06:41 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 17:06:41 INFO - Using env: {'DISPLAY': ':0', 17:06:41 INFO - 'HOME': '/home/cltbld', 17:06:41 INFO - 'LANG': 'en_US.UTF-8', 17:06:41 INFO - 'LANGUAGE': 'en_US:en', 17:06:41 INFO - 'LOGNAME': 'cltbld', 17:06:41 INFO - 'MAIL': '/var/mail/cltbld', 17:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:41 INFO - 'MOZ_NO_REMOTE': '1', 17:06:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:41 INFO - 'NO_EM_RESTART': '1', 17:06:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:41 INFO - 'PWD': '/builds/slave/test', 17:06:41 INFO - 'SHELL': '/bin/bash', 17:06:41 INFO - 'SHLVL': '1', 17:06:41 INFO - 'TERM': 'linux', 17:06:41 INFO - 'TMOUT': '86400', 17:06:41 INFO - 'USER': 'cltbld', 17:06:41 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:41 INFO - '_': '/tools/buildbot/bin/python'} 17:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:06:41 INFO - Running setup.py (path:/tmp/pip-vQUrGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:06:41 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)) 17:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:06:41 INFO - Running setup.py (path:/tmp/pip-rNdqdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:06:41 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)) 17:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:06:41 INFO - Running setup.py (path:/tmp/pip-34KSDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:06:41 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)) 17:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:06:41 INFO - Running setup.py (path:/tmp/pip-zcZ_di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:06:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 17:06:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:06:41 INFO - Running setup.py (path:/tmp/pip-pGZ_zn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:06:42 INFO - Running setup.py (path:/tmp/pip-LzpJdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:06:42 INFO - Running setup.py (path:/tmp/pip-hOLCWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:06:42 INFO - Running setup.py (path:/tmp/pip-Fk3LD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:06:42 INFO - Running setup.py (path:/tmp/pip-gZT1k3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:06:42 INFO - Running setup.py (path:/tmp/pip-3DGCou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:06:42 INFO - Running setup.py (path:/tmp/pip-vF3v_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:06:42 INFO - Running setup.py (path:/tmp/pip-rtla40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:06:42 INFO - Running setup.py (path:/tmp/pip-h5YVLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:06:42 INFO - Running setup.py (path:/tmp/pip-PbN1Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:42 INFO - Running setup.py (path:/tmp/pip-CluWzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:06:42 INFO - Running setup.py (path:/tmp/pip-t8fOVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:06:42 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)) 17:06:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:06:42 INFO - Running setup.py (path:/tmp/pip-UANgmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:06:42 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)) 17:06:42 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)) 17:06:42 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)) 17:06:42 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)) 17:06:42 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:06:42 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:06:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:06:42 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 17:06:42 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 17:06:42 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 17:06:42 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 17:06:42 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 17:06:42 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 17:06:44 INFO - Downloading blessings-1.6.tar.gz 17:06:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:06:44 INFO - Installing collected packages: blessings 17:06:44 INFO - Running setup.py install for blessings 17:06:45 INFO - Successfully installed blessings 17:06:45 INFO - Cleaning up... 17:06:45 INFO - Return code: 0 17:06:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:06:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:06:45 INFO - Reading from file tmpfile_stdout 17:06:45 INFO - Current package versions: 17:06:45 INFO - argparse == 1.2.1 17:06:45 INFO - blessings == 1.6 17:06:45 INFO - blobuploader == 1.2.4 17:06:45 INFO - docopt == 0.6.1 17:06:45 INFO - functools32 == 3.2.3-2 17:06:45 INFO - jsonschema == 2.5.1 17:06:45 INFO - manifestparser == 1.1 17:06:45 INFO - mozInstall == 1.12 17:06:45 INFO - mozcrash == 1.0 17:06:45 INFO - mozdebug == 0.1 17:06:45 INFO - mozdevice == 0.49 17:06:45 INFO - mozfile == 1.2 17:06:45 INFO - mozhttpd == 0.7 17:06:45 INFO - mozinfo == 0.9 17:06:45 INFO - mozleak == 0.1 17:06:45 INFO - mozlog == 3.4 17:06:45 INFO - moznetwork == 0.27 17:06:45 INFO - mozprocess == 0.23 17:06:45 INFO - mozprofile == 0.28 17:06:45 INFO - mozrunner == 6.13 17:06:45 INFO - mozscreenshot == 0.1 17:06:45 INFO - mozsystemmonitor == 0.3 17:06:45 INFO - moztest == 0.8 17:06:45 INFO - mozversion == 1.4 17:06:45 INFO - psutil == 3.1.1 17:06:45 INFO - requests == 1.2.3 17:06:45 INFO - wsgiref == 0.1.2 17:06:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:06:45 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')]} 17:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 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')]} 17:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:45 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 17:06:45 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 17:06:45 INFO - Using env: {'DISPLAY': ':0', 17:06:45 INFO - 'HOME': '/home/cltbld', 17:06:45 INFO - 'LANG': 'en_US.UTF-8', 17:06:45 INFO - 'LANGUAGE': 'en_US:en', 17:06:45 INFO - 'LOGNAME': 'cltbld', 17:06:45 INFO - 'MAIL': '/var/mail/cltbld', 17:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:45 INFO - 'MOZ_NO_REMOTE': '1', 17:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:45 INFO - 'NO_EM_RESTART': '1', 17:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:45 INFO - 'PWD': '/builds/slave/test', 17:06:45 INFO - 'SHELL': '/bin/bash', 17:06:45 INFO - 'SHLVL': '1', 17:06:45 INFO - 'TERM': 'linux', 17:06:45 INFO - 'TMOUT': '86400', 17:06:45 INFO - 'USER': 'cltbld', 17:06:45 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:45 INFO - '_': '/tools/buildbot/bin/python'} 17:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 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)) 17:06:45 INFO - Cleaning up... 17:06:45 INFO - Return code: 0 17:06:45 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:06:45 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')]} 17:06:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 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')]} 17:06:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:06:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:06:45 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 0x1e2d1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1f3ea80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x219f480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, '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': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', '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 17:06:45 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 17:06:45 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 17:06:45 INFO - Using env: {'DISPLAY': ':0', 17:06:45 INFO - 'HOME': '/home/cltbld', 17:06:45 INFO - 'LANG': 'en_US.UTF-8', 17:06:45 INFO - 'LANGUAGE': 'en_US:en', 17:06:45 INFO - 'LOGNAME': 'cltbld', 17:06:45 INFO - 'MAIL': '/var/mail/cltbld', 17:06:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:06:45 INFO - 'MOZ_NO_REMOTE': '1', 17:06:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:06:45 INFO - 'NO_EM_RESTART': '1', 17:06:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:06:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:06:45 INFO - 'PWD': '/builds/slave/test', 17:06:45 INFO - 'SHELL': '/bin/bash', 17:06:45 INFO - 'SHLVL': '1', 17:06:45 INFO - 'TERM': 'linux', 17:06:45 INFO - 'TMOUT': '86400', 17:06:45 INFO - 'USER': 'cltbld', 17:06:45 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:06:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:06:45 INFO - '_': '/tools/buildbot/bin/python'} 17:06:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:06:45 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:06:45 INFO - Cleaning up... 17:06:45 INFO - Return code: 0 17:06:45 INFO - Running post-action listener: _resource_record_post_action 17:06:45 INFO - Running post-action listener: _start_resource_monitoring 17:06:45 INFO - Starting resource monitoring. 17:06:45 INFO - [mozharness: 2016-12-30 01:06:45.887327Z] Finished create-virtualenv step (success) 17:06:45 INFO - [mozharness: 2016-12-30 01:06:45.887833Z] Running install step. 17:06:45 INFO - Running pre-action listener: _resource_record_pre_action 17:06:45 INFO - Running main action method: install 17:06:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:06:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:06:46 INFO - Reading from file tmpfile_stdout 17:06:46 INFO - Detecting whether we're running mozinstall >=1.0... 17:06:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:06:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:06:46 INFO - Reading from file tmpfile_stdout 17:06:46 INFO - Output received: 17:06:46 INFO - Usage: mozinstall [options] installer 17:06:46 INFO - Options: 17:06:46 INFO - -h, --help show this help message and exit 17:06:46 INFO - -d DEST, --destination=DEST 17:06:46 INFO - Directory to install application into. [default: 17:06:46 INFO - "/builds/slave/test"] 17:06:46 INFO - --app=APP Application being installed. [default: firefox] 17:06:46 INFO - mkdir: /builds/slave/test/build/application 17:06:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:06:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:07:03 INFO - Reading from file tmpfile_stdout 17:07:03 INFO - Output received: 17:07:03 INFO - /builds/slave/test/build/application/firefox/firefox 17:07:03 INFO - Running post-action listener: _resource_record_post_action 17:07:03 INFO - [mozharness: 2016-12-30 01:07:03.533323Z] Finished install step (success) 17:07:03 INFO - [mozharness: 2016-12-30 01:07:03.533570Z] Running run-tests step. 17:07:03 INFO - Running pre-action listener: _resource_record_pre_action 17:07:03 INFO - Running main action method: run_tests 17:07:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:07:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:07:03 INFO - Python 2.7.3 17:07:03 INFO - Return code: 0 17:07:03 INFO - grabbing minidump binary from tooltool 17:07:03 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')]} 17:07:03 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 0x219c820>, '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 0x219d520>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x219d9b0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:07:03 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 17:07:03 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 17:07:03 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 17:07:03 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:07:03 INFO - Return code: 0 17:07:03 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:07:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:07:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:07:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:07:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:07:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:07:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:07:03 INFO - Using env: {'DISPLAY': ':0', 17:07:03 INFO - 'HOME': '/home/cltbld', 17:07:03 INFO - 'LANG': 'en_US.UTF-8', 17:07:03 INFO - 'LANGUAGE': 'en_US:en', 17:07:03 INFO - 'LOGNAME': 'cltbld', 17:07:03 INFO - 'MAIL': '/var/mail/cltbld', 17:07:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:07:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:07:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:07:03 INFO - 'MOZ_NO_REMOTE': '1', 17:07:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:07:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:07:03 INFO - 'NO_EM_RESTART': '1', 17:07:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:07:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:07:03 INFO - 'PWD': '/builds/slave/test', 17:07:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:07:03 INFO - 'SHELL': '/bin/bash', 17:07:03 INFO - 'SHLVL': '1', 17:07:03 INFO - 'TERM': 'linux', 17:07:03 INFO - 'TMOUT': '86400', 17:07:03 INFO - 'USER': 'cltbld', 17:07:03 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906', 17:07:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:07:03 INFO - '_': '/tools/buildbot/bin/python'} 17:07:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:07:03 INFO - mozversion application_buildid: 20161229153015 17:07:03 INFO - mozversion application_changeset: 72e5976fb87ae161a9be1726e2311c6eba40278a 17:07:03 INFO - mozversion application_display_name: Nightly 17:07:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:07:03 INFO - mozversion application_name: Firefox 17:07:03 INFO - mozversion application_remotingname: firefox 17:07:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:07:03 INFO - mozversion application_vendor: Mozilla 17:07:03 INFO - mozversion application_version: 53.0a1 17:07:03 INFO - mozversion platform_buildid: 20161229153015 17:07:03 INFO - mozversion platform_changeset: 72e5976fb87ae161a9be1726e2311c6eba40278a 17:07:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 17:07:03 INFO - mozversion platform_version: 53.0a1 17:07:03 INFO - using testdate: 1483060023 17:07:03 INFO - actual date: 1483060023 17:07:03 INFO - starting webserver on 'localhost:53788' 17:07:03 INFO - SUITE-START | Running 2 tests 17:07:03 INFO - TEST-START | dromaeo_css 17:07:03 INFO - operating with platform_type : linux_ 17:07:03 INFO - Initialising browser for dromaeo_css test... 17:07:03 INFO - TEST-INFO | started process 26409 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpakd7bm/profile http://localhost:53788/getInfo.html) 17:07:04 INFO - PROCESS | 26409 | 1483060024243 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:07:08 INFO - PROCESS | 26409 | __metrics Screen width/height:1600/1200 17:07:08 INFO - PROCESS | 26409 | colorDepth:24 17:07:08 INFO - PROCESS | 26409 | Browser inner width/height: 1024/697 17:07:08 INFO - PROCESS | 26409 | __metrics 17:07:09 ERROR - PROCESS | 26409 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 17:07:10 INFO - TEST-INFO | 26409: exit 0 17:07:10 INFO - Browser initialized. 17:07:10 INFO - Running cycle 1/1 for dromaeo_css test... 17:07:10 INFO - TEST-INFO | started process 26530 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpakd7bm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:07:21 INFO - PROCESS | 26530 | RSS: Main: 154230784 17:07:21 INFO - PROCESS | 26530 | 17:10:22 INFO - PROCESS | 26530 | Cycle 1(1): loaded http://localhost:53788/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53788/tests/dromaeo/cssquery-ext.html) 17:10:23 INFO - PROCESS | 26530 | RSS: Main: 163905536 17:10:23 INFO - PROCESS | 26530 | 17:12:10 INFO - PROCESS | 26530 | ************************* 17:12:10 INFO - PROCESS | 26530 | A coding exception was thrown and uncaught in a Task. 17:12:10 INFO - PROCESS | 26530 | 17:12:10 INFO - PROCESS | 26530 | Full message: ReferenceError: fetch is not defined 17:12:10 INFO - PROCESS | 26530 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 17:12:10 INFO - PROCESS | 26530 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 17:12:10 INFO - PROCESS | 26530 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 17:12:10 INFO - PROCESS | 26530 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:12:10 INFO - PROCESS | 26530 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:12:10 INFO - PROCESS | 26530 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:12:10 INFO - PROCESS | 26530 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 17:12:10 INFO - PROCESS | 26530 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 17:12:10 INFO - PROCESS | 26530 | TaskImpl@resource://gre/modules/Task.jsm:277:3 17:12:10 INFO - PROCESS | 26530 | asyncFunction@resource://gre/modules/Task.jsm:252:14 17:12:10 INFO - PROCESS | 26530 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 17:12:10 INFO - PROCESS | 26530 | 17:12:10 INFO - PROCESS | 26530 | ************************* 17:13:25 INFO - PROCESS | 26530 | Cycle 1(1): loaded http://localhost:53788/tests/dromaeo/cssquery-ext.html (next: http://localhost:53788/tests/dromaeo/cssquery-jquery.html) 17:13:26 INFO - PROCESS | 26530 | RSS: Main: 158765056 17:13:26 INFO - PROCESS | 26530 | 17:16:28 INFO - PROCESS | 26530 | Cycle 1(1): loaded http://localhost:53788/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53788/tests/dromaeo/cssquery-mootools.html) 17:16:29 INFO - PROCESS | 26530 | RSS: Main: 165466112 17:16:29 INFO - PROCESS | 26530 | 17:19:30 INFO - PROCESS | 26530 | Cycle 1(1): loaded http://localhost:53788/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53788/tests/dromaeo/cssquery-prototype.html) 17:19:31 INFO - PROCESS | 26530 | RSS: Main: 160448512 17:19:31 INFO - PROCESS | 26530 | 17:22:34 INFO - PROCESS | 26530 | Cycle 1(1): loaded http://localhost:53788/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53788/tests/dromaeo/cssquery-yui.html) 17:22:34 INFO - PROCESS | 26530 | RSS: Main: 165289984 17:22:34 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | Cycle 1(1): loaded http://localhost:53788/tests/dromaeo/cssquery-yui.html (next: http://localhost:53788/tests/dromaeo/cssquery-dojo.html) 17:25:37 INFO - PROCESS | 26530 | RSS: Main: 163995648 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | __start_tp_report 17:25:37 INFO - PROCESS | 26530 | _x_x_mozilla_page_load 17:25:37 INFO - PROCESS | 26530 | _x_x_mozilla_page_load_details 17:25:37 INFO - PROCESS | 26530 | |i|pagename|runs| 17:25:37 INFO - PROCESS | 26530 | |0;dojo.html;3213.325201707641;3251.8536665850047;3266.689664481875;3272.492763621638;3274.901752947412;5279.049771041211;5518.862028449291;5557.972210138949;5566.888662226753;5569.415211402805;5328.120860058096;5440.483154100354;5446.019716451037;5461.69960652164;5556.333240011194;4865.440474345452;4887.364642596457;4918.508149185071;4981.227909674007;5018.523240292185;4842.322074909498;4983.352164218646;4986.75066246688;4991.875203119915;5016.197408414654;4818.879528011793;5044.848654540369;5058.873528161788;5074.594032477393;5147.253648221005;4869.82955596556;4893.7063776173545;4896.412430508326;4944.456109827916;5025.899482010339;4959.20652695567;4963.60291176705;4968.776405061781;4983.700977941335;5013.323201367805;5438.592105592096;5443.8094666686875;5459.426760190163;5465.754041068161;5476.205950137248;5615.298087739033;5657.830265092054;5813.331466881321;5823.796167134173;5841.386654401312;5885.470307672287;5922.763089476416;5950.9702451487465;5954;6013.188219590387;5446.046941785211;5475.233467314579;5513.889722205574;5517.944820551824;5521.5306698931045;5818.76724930998;5886;5932.199153114274;5977.103434484862;6074.058520929263;5747.48272655463;5760.827175184667;5769.163471296639;5798;5830.212921255661;5803.825885223366;5810.1865738796605;5845.941540584539;5862.413758624189;5868.149118377812;5583.804566840141;5594.496495315441;5598.804041858515;5725.4274572543245;5733.77064917407;5674.432556744293;5687.544996400278;5689.68706721126;5784.161296611967;5787.189793428924;4338.522762496123;4361.9127617447475;4371.868843934623;4392.670549708785;4394.209042372339;3905.3556163232747;3911.6700321890694;3912.6283003115227;3929.0766669832565;3940.9605903941165;5385;5419.918701219485;5494.587905906993;5498.120300751861;5609.270794796732;5782.537397008229;5795.7391917362975;5806.970965145148;5838.620489668158;5876.353601103875;5576.86057848557;5592.245046918695;5606.327240731057;5631.014572449805;5640.661560306395;4660.184467718203;4747.216709242936;4841.443234028132;4873.220284754422;4921.729304888194;6106.694665266719;6180.6291622502795;6185.072239164071;6186.566940314135;6188.690565471708;5898.056310990221;5899.941000590111;5906.291245050621;5927.614705044159;5941.524678025661;5947.1079338099635;5989.251343582052;6169.475594574512;6173.722182501709;6181.84545386369;5745.942540574708;5781.161731548986;5791.739371728198;5840.240768700041;5879.588428809942;5861.794837180678;5924.4667979882015;5928.288605367384;5929.733162007634;5936.851578710394;5581.441855814549;5739.426057394394;5796.739146738322;5807.738651760596;5808.796692115756;5605.831825045255;5668.149777533403;5673.801416950387;5686.346070201815;5793.36273009977;5672.602917795714;5711.228984087095;5712.6286791358425;5718.571107166896;5728.656280623176;5601.187827764869;5634.6337488063145;5710.543156547383;5774.740136693943;5777.306723193075;5584.972075139598;5613.522850557748;5622;5652.152177173457;5706.3152421709665 17:25:37 INFO - PROCESS | 26530 | |1;ext.html;532.3319234360878;557.2171099605056;565.0055901613165;574.635106707241;576.4437317988142;40493.392599111;42483.78758106209;42710;46254.306185407164;46316.3052554212;43731.562684373144;44129.77935110328;44501.332480012825;44542.33186502205;44657.330140047925;8820;8955.373123881298;8967.461952282883;8977.640894364251;10092.293539452241;10389;10389.064984151424;10389.740256493611;10432.843507347357;10448.373097614129;10530;10568.577256909715;10573.154147668207;10579.153667706603;10599.682009539687;10395.064444199983;10426.63506777263;10479.318844275174;10480.423576703279;10485.685429437053;10233.334833235816;10337.483125843677;10342.379457232515;10363.79272414555;10365.792684146274;36954.44568331477;36957.81521092404;37148.25703485942;37391;37436.251274974624;3976.642102210786;4012.198513926967;4061.8375264989363;4103.425520427142;4115.753054816721;27979.440411191663;28610;29017.274568135756;29191.562126567907;29565.260868478246;4210.9578904210875;4340.435743353375;4395.692301538894;4397.406350142722;4420.248557745191;337752;340936;342874;343150.9999999975;344404;51032.234516482284;51557.74221128871;51624.99999999925;51786;51821;53539;53610.463895361325;53696;53722.7313863436;53809.730951344995;14868.553943381716;15189.240537973279;15346.155961422006;15356.539303820904;15358.078515289119;11003.61487347939;11059.336439813636;11251.43742812856;11774.529018839321;12022.098342624338;16449.83550164483;16476.258568364454;16480.917595411946;16486.587835304214;16655;6552.50856185788;6723.125993620798;6740.764073257413;6903;6905.792826215222;895;918.6574235698941;924.713338864954;928.1553786054793;950.538988590533;29906.80372785108;30007;30136.397272054874;30264;31942.840285798888;1052.1109662335314;1083.4636854756789;1087.853139826129;1090.5201711247025;1091.3942761767296;15648.530544083696;16031.358745650277;16211.918940405223;16329.91835040841;16578.253978570992;492801;498871;499231;505554;509150;58299.70850145722;58538;58674.11988820171;58999.41000589939;59029.11456328158;1346.548906116462;1374.5670113914084;1380.7376598446263;1389.770687836516;1392.9164250145025;28670.856645716638;29290.121296361143;29616.703832961393;29681.109566713032;30249.848750756104;49515.50484495253;49609.50390496049;49999.75000124976;50081;50170;389536;390036;390097;391309;391937;24851;25255.242342729012;25355.873220633777;26053.218403447925;27022.594661080493;41394;41491.58508414877;41886.79056604698;43011.13977720491;43091.138177236906;34702.82648586741;34714.47928281027;35349.29301414009;35425.46861797023;35919.820400898876;34697.1014927468;34917.650823492455;34991.82504087463;35077.64922350744;35427.645723543465;32424.675753242165;33231.33537329288;34683.30633387268;35401.645983539835;35847.283054338244;29810.552841707824;31570.210744731565;32019.67980320167;32122.51816222712;32173.517397239528;24504.87747561251;24664.630030549197;24869.626955596043;25165.370865728502;25262.115825946003 17:25:37 INFO - PROCESS | 26530 | |2;jquery.html;393.9566647668756;400.8817398867334;411.2535747618073;414.8465067924868;422.11988005009556;18380.540486487844;18528.72206916894;18926.716099258523;19162.329318473858;19167.80832191676;17882.28470861167;18114.27542898286;18995;19507.317243896465;19744.70382944257;5545.140503222007;5607.551395888339;5804.535637149039;5818.301803783552;5837.328707198662;5894.469497745202;5908.527317814586;5920.763169473216;5928.347881733006;5946.108083787445;5755.9424405756035;6380.808575742735;6436.517261205405;6460.903086453707;6478.838029049264;5864.325602555718;6025.367336429656;6374.713137908782;6423.550351475398;6430.839229019265;5659.943400565983;5662.235598194274;5692.715364231772;5694.658320500742;5726.885462290731;16064.678706425806;20742.688859667116;21567.1373145074;21677.132914683392;22048.779512204834;2577.510273048115;2633.947321053568;2668.426288347995;2760.6687197536426;2768.1418760184406;8946.105389461067;9497.90502094984;9728.73526441558;10042.849357259647;10308;1990.8208261256411;2001.4996250937265;2012.8087831655969;2013.134352228541;2017.3342796876996;75231;77361.61319193424;78846.6057669708;79670.20329796686;80004.19995800026;10208.948955255177;10362.585496580052;10473.84289235662;10703.250772445854;11058.336499810037;13467.730645387037;13544.93227533856;13561;13622.318884055956;13630.591082267549;7432.888506672404;7596.924030759622;7652.365648024617;7895.565743884142;7938.483998540077;7283.490155689157;7339.779806605704;7364.889526657104;7530.246975302426;7580.772576822704;4669.673122881433;4704.435467743892;4707.7881495332795;4715.693479923794;4882.8779280517565;1902.5243689077731;1946.240966023252;1977.2980591889673;1991.601679664073;1992.7608686957658;898.4025622960658;911.9908800911905;918.6279556779515;922.1654402765508;925.5696101312922;17069.40257090996;17317.7402338965;17526.386576469762;17821.643567128584;18344.633107337777;915.3729695158704;926.4070994563485;934.3506263147048;937.4656445825814;940.8588711693301;10364.896351036545;10410.791784164427;10513.52689128991;10556.683299501028;10601;416624;418361;421348;423569;425336;81239.28226818443;82360.58819705863;82551.58724206341;86423.56788216019;86732.56633716918;996.9202463802878;1008.7679833638305;1026.7587117027492;1027.3630349183554;1027.825269704152;15921.283542240395;17058;17229.56926076858;17361.82638173602;17381.56546086358;35963.46054809232;37256.44115338218;40777.796111019255;41537.37693934654;41799.37300940428;52358;53301.46698532965;53415.46584534105;54363;54815;8331.583420828812;8499.065102838806;8545.401821872409;8737.694180703898;8839.51382673959;9644.469554174588;9647;10675.839862401916;12655;12882.355882205664;10769.9461502692;11188.552457901918;11436.485358158736;11473.254238474472;11704.297742135499;6827.283135270725;7392.8891066635115;11221.102311814871;11526.884731152917;11721.824172637247;6660.1341825564305;8923.107689231285;10900;11595.304281743123;11656.592019279624;7385.556866588022;7391.6304184791625;7555.433342499225;7708.537487750753;7783.688652453839;6012.849678758066;11880.524779008743;12035.518579256732;12199.69500762488;12222.144449888423 17:25:37 INFO - PROCESS | 26530 | |3;mootools.html;1355.9118657287277;1359.0962010263177;1359.8844098251654;1364.5565191312826;1375.2229990055619;7550.6224688765615;7722.8455430891345;7976.2821346078845;8034.0760812506505;8036.116027237006;7976;8119.959400203005;8145.34837213023;8146.551939643317;8158.836823263531;2264.275431861801;2281.589313923493;2299.8505097168713;2300.447892505795;2309.180241014441;2246.427161073932;2284;2302.1827251325703;2342.4612339162004;2359.0563774490256;2287.5996700577416;2297.896594653236;2303.0442366417915;2303.9654405183937;2305.3890718959487;2185.4864106934856;2199.3511913985412;2202.0751284460407;2202.9315781845794;2206;2231.308294428716;2245.764194759543;2286.8513546619415;2291.1522736587403;2308.1690591387087;7779.688812447495;7915.287624113857;7927.048754149481;7938.404619653491;7945.562994035326;4813.55593322002;4959.206526955706;4967.751612419364;4974.751262436864;4978.228374601942;3264.1778394205644;3335.966640333614;3351.8491667875005;3371.3088816792497;3380.8985730428126;1351.202790353696;1413.766728489798;1421.7938398932097;1457.147568672328;1524.8932574719663;10549.789004219949;10614.946925265323;10689.358638481715;10718.464076796128;10758.946205269001;3903.882883513499;3956.188981258835;4115.0741083256025;4122.773247471418;4130.524989626231;8417.410781245255;8431.28334091609;8437.240648341678;8443.282321002784;8467.830643387098;4698.130845793539;4892.657513974059;4964.627652926045;4971.080350135237;4978.253262010728;4626.421697287839;4627.421572303462;4658.790354434059;4678.391809064868;4680.081166721696;3459.9654003460146;3543.1319326765106;3550.573931128281;3553.2715793262837;3617.746757726934;1248.450681700049;1281.9679508012375;1290.9419076141596;1291.547958214636;1292.7414517096618;908.9909100909039;917.3349321741684;930.1767935377104;933.3419938943028;957.2963871554402;5323.467653234646;5486.588505862076;5640.915386269209;5733;5750.396208398141;934.9158575728217;961.6393852305386;966.415318732157;978.9804203916025;1008.3344992305043;3670.8348124333934;3731.9253614927547;3794.4877441545036;3855.363864962306;3882.728209025341;9004.099590040943;9425.340226184238;9429.76425589352;9435.952820235854;9468.763280917894;9350.205232555172;9368.110029547182;9402.670906518239;9415.246780257563;9418.246540276761;7218.3503484686635;7248.4563657725885;7286.380657644159;7452.217517160729;7456.51532650376;5352.40408401917;5389.003034438641;5407;5425.077736784835;5605.271314729059;8703.433381991303;8717.084706106021;8762.211400973943;8772.692955746517;8803.723460098121;8435.072142064491;8672.306215502871;8675;8844.46933184011;8854.690085846965;7044.823879403056;7182;7200.351968322876;7212.350888420067;7225.24134965821;6380.649064301508;7367.668454919434;7429.03422555086;7466.440016998638;7515.286047825448;6212.844678883064;6464.709088090953;6533.477321814338;6625.734970601122;6655.667216639052;6489.383508566679;6710.16122984627;6729.192496900403;6736.494762892705;6770.627615481196;5920.319163296276;5951.55363347742;5977.701114944149;6031.969840150771;6069.9089513656445;4644.256918892961;4678.64319347399;4707.505711900201;4724.6692731508465;4784.760761961818;4549.522300158435;4624.560666736655;4625.213713668617;4637;4696.882577935579 17:25:37 INFO - PROCESS | 26530 | |4;prototype.html;402.1293898709294;402.1735333888859;408.90390758171844;409.1714278585863;415.71731222768506;5313.8937221255555;5405;5425.724954635657;5427.674339539625;5434.673919564819;5211.86970325742;5278.868028299295;5290.68255904646;5302.549283310923;5379.81170659027;4272.145570885819;4273.764942928137;4292.356146578023;4294.914101717972;4309.095090031082;4301.3547967804925;4316.007318316789;4331.7400955942585;4332.350147477872;4344.847930322439;4164.979175104136;4202.936955945664;4250.61744443;4303.505096913864;4327.848525301615;4260.723053001545;4262;4262.552431994627;4266.210751011057;4288.699791014615;3854.8265328060306;3855.132595166065;3856.267309211241;3868.3810590305698;3880.7671539707426;5146.562542183919;5189.818356357509;5334.786608535654;5335.3864305604575;5355.892882142374;4827.630993950481;4877.365942427497;4916.975415122902;4926.162552366106;4959.752012399402;5589.692566908819;5610.298712660918;5625.1843482695185;5635.830925072254;5672.893785711788;4130.339145736698;4243;4269.633717210494;4279.87160385189;4297.742135471851;5615;5763.567732420085;5787.942120578825;5794.942050579524;5802.912956305659;4114.189756507128;4155.958440415558;4161.708680392343;4161.854335098257;4162.875113746592;4186.099988502487;4204.159168166379;4211.557786432441;4216.789160542022;4226.5773422657485;4508.819647214074;4768.47546769855;4804.927926081112;4809;4816.927746083811;5598.692071936082;5606.887862242814;5612.242347283064;5612.652963288863;5617.662940223599;1413.3957957718064;1413.9607388569393;1417.6597616571948;1420.88632909367;1425;953.5899563187758;987.6247026130168;988.7083310423442;993.9801203976025;1013.6452241715488;5282.286891269706;5286.414075777251;5322.8935421292135;5361.579181516901;5369.201317558662;5566.888662226733;5601.075822489243;5608.439156084359;5628.183913332544;5667.688277144714;5054.595632349403;5189.273501709764;5266.420693723688;5281.551068159172;5291.6872748709675;2912.927176820554;2948.142670117812;2959.186223788475;2961.4225226080707;3020.5016172331757;5841;5845.025382739693;5848.976429124886;5854.297484301911;5874.706264686749;5588.413216612278;5878.823635290948;5906.468417842415;5937.079752638261;5949.196858424058;6108.816735497942;6147.938520614826;6168.414000669929;6169.321374648785;6200.596961197598;5661.858453538694;5817.389174136824;5835.4748072673665;5869.941300586939;5874.265716785402;5745.971270143623;5754.3382511010095;5761.625494342854;5772.884542309214;5780;5852.385499522489;6035;6086.013661052018;6092.9086063709965;6106.267247930276;4158.459400278066;4186.790660467025;4196.412502249748;4215.704900656925;4218.789060546899;5682.289713785777;5862.208601838868;5874;5896.6756828374855;5923.289205295392;5678.602497825278;5679.602427830012;5680.2047713321;5704.372519022987;5725.828225153252;5659.528522584075;5674.609720618475;5679.829605111853;5682.6306290091015;5708.828735137952;5542.2795036644975;5612.831615051555;5650.802221922213;5671.631343962629;5685.374608793112;5721.227634269487;5735.3977832327;5777.768889244383;5778.393268706725;5796.710164491843;5554.611347163209;5580.525655319343;5590.916136257878;5607.018771715015;5619.831405057855 17:25:37 INFO - PROCESS | 26530 | |5;yui.html;732.3372348025036;768.2317682317682;793.992060079399;817.6811043692959;826.1655727715006;14694.41222351106;15180.54458366248;15490.690186196269;15581.610459738513;15834.208289585504;13207.603771886836;13657.112287701317;13826.792598111037;13829.723405531908;13962;812.5896422306727;834.5493433545878;836.8912041434605;837.7779888329567;838.714836955435;830.3315830756238;834.6160766047625;836.8284501677172;837.2297486312576;838.651959436833;854.9914500855006;856.9485830850138;864.5720368417642;865.1651156633847;866.1079088538784;857.7855536115972;858.4248553469191;862.2369203255101;863.223099210709;866.4671227195292;633.2116514938931;637.6939069246741;638.0270088115615;638.4190386748083;639.9104125422444;12593.622191334274;12595.181313214613;12645.557405490763;12653.36733163338;12748.681282968;10827.29622574538;11177.944110279397;11183.776324473547;11196;11514.078873690169;11036.392998385085;11071.446427678664;11122.721931951684;11145.498452569655;11157.66527004191;3673.5775385830702;3696.685781708558;3764.4353346997896;3778.03660066684;3789.3179227738997;73964.63017684931;74573;75335;76082;76811;9090.86363704545;9198.218151457068;9238.44569325829;9248.676296329597;9258.814823703487;17823.910880445514;17879.106044697714;17904;17910.641787164444;17921.193546290448;7286.635668216674;7740.4194685398825;7843.803904902309;8156.714514992065;8167.673293068211;4354.477462704433;4517.774111294488;4539.16025535277;4595.6528410975025;4607;3214.7428205743486;3300.389427955788;3315.1049216711835;3316.4859446785786;3319.0872510059926;580.4920694392407;581.1573218832625;591.8342863998088;593.2643522032648;594.5897330841706;684.4319215051495;684.8835697931364;695.1762161838238;697.4803771190495;699.6606645776794;8791.164839340252;8833.60248788806;9007;9057.18485336323;9164.770880728036;647.8769033883547;654.738104758091;661.9371159739817;662.6885363878969;670.4267850987433;4184.832606695698;4367.541408152162;4412.911741765147;4416.580424859633;4431.689781715249;80861;85397.4380768578;86077.56961215154;86140.56929715311;86519;71911.2808871915;73811.26188738151;73883.63058184783;74228;74777.25222747812;64833.35166648367;64872.35127648663;65092.34907650958;65199.67400162969;65654;8275.50346979183;8474;8556.657733690832;8569.271611913056;8669.263112635244;43574.782126089165;44145.11709765851;45461.31808022948;45536.861578460805;46315.66318062781;48078.75960620175;48692;50118.49881501284;53120.359955005624;55238;12683.365831708194;12846.614601561969;12867.420966056363;12881.871181288067;12889.548865789653;15290.694186116438;15320.31058602388;15497.612559686098;15757.133357665438;16026.759598605797;13946.511872084428;14121.364538595582;14343.56969290923;14580.416783328548;14600.12399256048;14064;14648.926755366156;14667.633309167357;14980;15640.374385024472;13536.526221582197;13707.794383084061;14249.786253206003;14252;14610.707785844012;11267.718307042389;11553.24903881245;11630.825537616773;11919.46362413676;12049.81925271104;11148.944255278671;11256.718582035515;11511;11571.594994175164;11579.247348922294 17:25:37 INFO - PROCESS | 26530 | __end_tp_report 17:25:37 INFO - PROCESS | 26530 | __start_cc_report 17:25:37 INFO - PROCESS | 26530 | _x_x_mozilla_cycle_collect,322 17:25:37 INFO - PROCESS | 26530 | __end_cc_report 17:25:37 INFO - PROCESS | 26530 | __startTimestamp1483061137637__endTimestamp 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | ------- Summary: start ------- 17:25:37 INFO - PROCESS | 26530 | Number of tests: 6 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | [#0] dojo.html Cycles:165 Average:5424.13 Median:5622.00 stddev:627.55 (11.2%) stddev-sans-first:605.04 17:25:37 INFO - PROCESS | 26530 | Values: 3213.3 3251.9 3266.7 3272.5 3274.9 5279.0 5518.9 5558.0 5566.9 5569.4 5328.1 5440.5 5446.0 5461.7 5556.3 4865.4 4887.4 4918.5 4981.2 5018.5 4842.3 4983.4 4986.8 4991.9 5016.2 4818.9 5044.8 5058.9 5074.6 5147.3 4869.8 4893.7 4896.4 4944.5 5025.9 4959.2 4963.6 4968.8 4983.7 5013.3 5438.6 5443.8 5459.4 5465.8 5476.2 5615.3 5657.8 5813.3 5823.8 5841.4 5885.5 5922.8 5951.0 5954.0 6013.2 5446.0 5475.2 5513.9 5517.9 5521.5 5818.8 5886.0 5932.2 5977.1 6074.1 5747.5 5760.8 5769.2 5798.0 5830.2 5803.8 5810.2 5845.9 5862.4 5868.1 5583.8 5594.5 5598.8 5725.4 5733.8 5674.4 5687.5 5689.7 5784.2 5787.2 4338.5 4361.9 4371.9 4392.7 4394.2 3905.4 3911.7 3912.6 3929.1 3941.0 5385.0 5419.9 5494.6 5498.1 5609.3 5782.5 5795.7 5807.0 5838.6 5876.4 5576.9 5592.2 5606.3 5631.0 5640.7 4660.2 4747.2 4841.4 4873.2 4921.7 6106.7 6180.6 6185.1 6186.6 6188.7 5898.1 5899.9 5906.3 5927.6 5941.5 5947.1 5989.3 6169.5 6173.7 6181.8 5745.9 5781.2 5791.7 5840.2 5879.6 5861.8 5924.5 5928.3 5929.7 5936.9 5581.4 5739.4 5796.7 5807.7 5808.8 5605.8 5668.1 5673.8 5686.3 5793.4 5672.6 5711.2 5712.6 5718.6 5728.7 5601.2 5634.6 5710.5 5774.7 5777.3 5585.0 5613.5 5622.0 5652.2 5706.3 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | [#1] ext.html Cycles:180 Average:56098.63 Median:34708.65 stddev:110336.41 (317.9%) stddev-sans-first:110567.06 17:25:37 INFO - PROCESS | 26530 | Values: 532.3 557.2 565.0 574.6 576.4 40493.4 42483.8 42710.0 46254.3 46316.3 43731.6 44129.8 44501.3 44542.3 44657.3 8820.0 8955.4 8967.5 8977.6 10092.3 10389.0 10389.1 10389.7 10432.8 10448.4 10530.0 10568.6 10573.2 10579.2 10599.7 10395.1 10426.6 10479.3 10480.4 10485.7 10233.3 10337.5 10342.4 10363.8 10365.8 36954.4 36957.8 37148.3 37391.0 37436.3 3976.6 4012.2 4061.8 4103.4 4115.8 27979.4 28610.0 29017.3 29191.6 29565.3 4211.0 4340.4 4395.7 4397.4 4420.2 337752.0 340936.0 342874.0 343151.0 344404.0 51032.2 51557.7 51625.0 51786.0 51821.0 53539.0 53610.5 53696.0 53722.7 53809.7 14868.6 15189.2 15346.2 15356.5 15358.1 11003.6 11059.3 11251.4 11774.5 12022.1 16449.8 16476.3 16480.9 16486.6 16655.0 6552.5 6723.1 6740.8 6903.0 6905.8 895.0 918.7 924.7 928.2 950.5 29906.8 30007.0 30136.4 30264.0 31942.8 1052.1 1083.5 1087.9 1090.5 1091.4 15648.5 16031.4 16211.9 16329.9 16578.3 492801.0 498871.0 499231.0 505554.0 509150.0 58299.7 58538.0 58674.1 58999.4 59029.1 1346.5 1374.6 1380.7 1389.8 1392.9 28670.9 29290.1 29616.7 29681.1 30249.8 49515.5 49609.5 49999.8 50081.0 50170.0 389536.0 390036.0 390097.0 391309.0 391937.0 24851.0 25255.2 25355.9 26053.2 27022.6 41394.0 41491.6 41886.8 43011.1 43091.1 34702.8 34714.5 35349.3 35425.5 35919.8 34697.1 34917.7 34991.8 35077.6 35427.6 32424.7 33231.3 34683.3 35401.6 35847.3 29810.6 31570.2 32019.7 32122.5 32173.5 24504.9 24664.6 24869.6 25165.4 25262.1 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | [#2] jquery.html Cycles:180 Average:26167.87 Median:210885.06 stddev:69619.62 (33.0%) stddev-sans-first:69788.02 17:25:37 INFO - PROCESS | 26530 | Values: 394.0 400.9 411.3 414.8 422.1 18380.5 18528.7 18926.7 19162.3 19167.8 17882.3 18114.3 18995.0 19507.3 19744.7 5545.1 5607.6 5804.5 5818.3 5837.3 5894.5 5908.5 5920.8 5928.3 5946.1 5755.9 6380.8 6436.5 6460.9 6478.8 5864.3 6025.4 6374.7 6423.6 6430.8 5659.9 5662.2 5692.7 5694.7 5726.9 16064.7 20742.7 21567.1 21677.1 22048.8 2577.5 2633.9 2668.4 2760.7 2768.1 8946.1 9497.9 9728.7 10042.8 10308.0 1990.8 2001.5 2012.8 2013.1 2017.3 75231.0 77361.6 78846.6 79670.2 80004.2 10208.9 10362.6 10473.8 10703.3 11058.3 13467.7 13544.9 13561.0 13622.3 13630.6 7432.9 7596.9 7652.4 7895.6 7938.5 7283.5 7339.8 7364.9 7530.2 7580.8 4669.7 4704.4 4707.8 4715.7 4882.9 1902.5 1946.2 1977.3 1991.6 1992.8 898.4 912.0 918.6 922.2 925.6 17069.4 17317.7 17526.4 17821.6 18344.6 915.4 926.4 934.4 937.5 940.9 10364.9 10410.8 10513.5 10556.7 10601.0 416624.0 418361.0 421348.0 423569.0 425336.0 81239.3 82360.6 82551.6 86423.6 86732.6 996.9 1008.8 1026.8 1027.4 1027.8 15921.3 17058.0 17229.6 17361.8 17381.6 35963.5 37256.4 40777.8 41537.4 41799.4 52358.0 53301.5 53415.5 54363.0 54815.0 8331.6 8499.1 8545.4 8737.7 8839.5 9644.5 9647.0 10675.8 12655.0 12882.4 10769.9 11188.6 11436.5 11473.3 11704.3 6827.3 7392.9 11221.1 11526.9 11721.8 6660.1 8923.1 10900.0 11595.3 11656.6 7385.6 7391.6 7555.4 7708.5 7783.7 6012.8 11880.5 12035.5 12199.7 12222.1 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | [#3] mootools.html Cycles:180 Average:5194.48 Median:5150.86 stddev:2768.36 (53.7%) stddev-sans-first:2761.10 17:25:37 INFO - PROCESS | 26530 | Values: 1355.9 1359.1 1359.9 1364.6 1375.2 7550.6 7722.8 7976.3 8034.1 8036.1 7976.0 8120.0 8145.3 8146.6 8158.8 2264.3 2281.6 2299.9 2300.4 2309.2 2246.4 2284.0 2302.2 2342.5 2359.1 2287.6 2297.9 2303.0 2304.0 2305.4 2185.5 2199.4 2202.1 2202.9 2206.0 2231.3 2245.8 2286.9 2291.2 2308.2 7779.7 7915.3 7927.0 7938.4 7945.6 4813.6 4959.2 4967.8 4974.8 4978.2 3264.2 3336.0 3351.8 3371.3 3380.9 1351.2 1413.8 1421.8 1457.1 1524.9 10549.8 10614.9 10689.4 10718.5 10758.9 3903.9 3956.2 4115.1 4122.8 4130.5 8417.4 8431.3 8437.2 8443.3 8467.8 4698.1 4892.7 4964.6 4971.1 4978.3 4626.4 4627.4 4658.8 4678.4 4680.1 3460.0 3543.1 3550.6 3553.3 3617.7 1248.5 1282.0 1290.9 1291.5 1292.7 909.0 917.3 930.2 933.3 957.3 5323.5 5486.6 5640.9 5733.0 5750.4 934.9 961.6 966.4 979.0 1008.3 3670.8 3731.9 3794.5 3855.4 3882.7 9004.1 9425.3 9429.8 9436.0 9468.8 9350.2 9368.1 9402.7 9415.2 9418.2 7218.4 7248.5 7286.4 7452.2 7456.5 5352.4 5389.0 5407.0 5425.1 5605.3 8703.4 8717.1 8762.2 8772.7 8803.7 8435.1 8672.3 8675.0 8844.5 8854.7 7044.8 7182.0 7200.4 7212.4 7225.2 6380.6 7367.7 7429.0 7466.4 7515.3 6212.8 6464.7 6533.5 6625.7 6655.7 6489.4 6710.2 6729.2 6736.5 6770.6 5920.3 5951.6 5977.7 6032.0 6069.9 4644.3 4678.6 4707.5 4724.7 4784.8 4549.5 4624.6 4625.2 4637.0 4696.9 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | [#4] prototype.html Cycles:180 Average:4777.88 Median:5374.51 stddev:1386.09 (25.8%) stddev-sans-first:1350.51 17:25:37 INFO - PROCESS | 26530 | Values: 402.1 402.2 408.9 409.2 415.7 5313.9 5405.0 5425.7 5427.7 5434.7 5211.9 5278.9 5290.7 5302.5 5379.8 4272.1 4273.8 4292.4 4294.9 4309.1 4301.4 4316.0 4331.7 4332.4 4344.8 4165.0 4202.9 4250.6 4303.5 4327.8 4260.7 4262.0 4262.6 4266.2 4288.7 3854.8 3855.1 3856.3 3868.4 3880.8 5146.6 5189.8 5334.8 5335.4 5355.9 4827.6 4877.4 4917.0 4926.2 4959.8 5589.7 5610.3 5625.2 5635.8 5672.9 4130.3 4243.0 4269.6 4279.9 4297.7 5615.0 5763.6 5787.9 5794.9 5802.9 4114.2 4156.0 4161.7 4161.9 4162.9 4186.1 4204.2 4211.6 4216.8 4226.6 4508.8 4768.5 4804.9 4809.0 4816.9 5598.7 5606.9 5612.2 5612.7 5617.7 1413.4 1414.0 1417.7 1420.9 1425.0 953.6 987.6 988.7 994.0 1013.6 5282.3 5286.4 5322.9 5361.6 5369.2 5566.9 5601.1 5608.4 5628.2 5667.7 5054.6 5189.3 5266.4 5281.6 5291.7 2912.9 2948.1 2959.2 2961.4 3020.5 5841.0 5845.0 5849.0 5854.3 5874.7 5588.4 5878.8 5906.5 5937.1 5949.2 6108.8 6147.9 6168.4 6169.3 6200.6 5661.9 5817.4 5835.5 5869.9 5874.3 5746.0 5754.3 5761.6 5772.9 5780.0 5852.4 6035.0 6086.0 6092.9 6106.3 4158.5 4186.8 4196.4 4215.7 4218.8 5682.3 5862.2 5874.0 5896.7 5923.3 5678.6 5679.6 5680.2 5704.4 5725.8 5659.5 5674.6 5679.8 5682.6 5708.8 5542.3 5612.8 5650.8 5671.6 5685.4 5721.2 5735.4 5777.8 5778.4 5796.7 5554.6 5580.5 5590.9 5607.0 5619.8 17:25:37 INFO - PROCESS | 26530 | 17:25:37 INFO - PROCESS | 26530 | [#5] yui.html Cycles:180 Average:17473.24 Median:48385.38 stddev:23069.46 (47.7%) stddev-sans-first:23099.93 17:25:37 INFO - PROCESS | 26530 | Values: 732.3 768.2 794.0 817.7 826.2 14694.4 15180.5 15490.7 15581.6 15834.2 13207.6 13657.1 13826.8 13829.7 13962.0 812.6 834.5 836.9 837.8 838.7 830.3 834.6 836.8 837.2 838.7 855.0 856.9 864.6 865.2 866.1 857.8 858.4 862.2 863.2 866.5 633.2 637.7 638.0 638.4 639.9 12593.6 12595.2 12645.6 12653.4 12748.7 10827.3 11177.9 11183.8 11196.0 11514.1 11036.4 11071.4 11122.7 11145.5 11157.7 3673.6 3696.7 3764.4 3778.0 3789.3 73964.6 74573.0 75335.0 76082.0 76811.0 9090.9 9198.2 9238.4 9248.7 9258.8 17823.9 17879.1 17904.0 17910.6 17921.2 7286.6 7740.4 7843.8 8156.7 8167.7 4354.5 4517.8 4539.2 4595.7 4607.0 3214.7 3300.4 3315.1 3316.5 3319.1 580.5 581.2 591.8 593.3 594.6 684.4 684.9 695.2 697.5 699.7 8791.2 8833.6 9007.0 9057.2 9164.8 647.9 654.7 661.9 662.7 670.4 4184.8 4367.5 4412.9 4416.6 4431.7 80861.0 85397.4 86077.6 86140.6 86519.0 71911.3 73811.3 73883.6 74228.0 74777.3 64833.4 64872.4 65092.3 65199.7 65654.0 8275.5 8474.0 8556.7 8569.3 8669.3 43574.8 44145.1 45461.3 45536.9 46315.7 48078.8 48692.0 50118.5 53120.4 55238.0 12683.4 12846.6 12867.4 12881.9 12889.5 15290.7 15320.3 15497.6 15757.1 16026.8 13946.5 14121.4 14343.6 14580.4 14600.1 14064.0 14648.9 14667.6 14980.0 15640.4 13536.5 13707.8 14249.8 14252.0 14610.7 11267.7 11553.2 11630.8 11919.5 12049.8 11148.9 11256.7 11511.0 11571.6 11579.2 17:25:37 INFO - PROCESS | 26530 | -------- Summary: end -------- 17:25:37 INFO - PROCESS | 26530 | 17:25:38 INFO - TEST-INFO | 26530: exit 0 17:25:38 INFO - TEST-OK | dromaeo_css | took 1114638ms 17:25:38 INFO - TEST-START | kraken 17:25:38 INFO - operating with platform_type : linux_ 17:25:38 INFO - Initialising browser for kraken test... 17:25:38 INFO - TEST-INFO | started process 26886 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5mFMA0/profile http://localhost:53788/getInfo.html) 17:25:38 INFO - PROCESS | 26886 | 1483061138878 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 17:25:43 INFO - PROCESS | 26886 | __metrics Screen width/height:1600/1200 17:25:43 INFO - PROCESS | 26886 | colorDepth:24 17:25:43 INFO - PROCESS | 26886 | Browser inner width/height: 1024/697 17:25:43 INFO - PROCESS | 26886 | __metrics 17:25:43 ERROR - PROCESS | 26886 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 17:25:44 INFO - TEST-INFO | 26886: exit 0 17:25:44 INFO - Browser initialized. 17:25:44 INFO - Running cycle 1/1 for kraken test... 17:25:44 INFO - TEST-INFO | started process 27007 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp5mFMA0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:25:56 INFO - PROCESS | 27007 | RSS: Main: 154824704 17:25:56 INFO - PROCESS | 27007 | 17:25:59 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53788/tests/kraken/driver.html?testName=audio-beat-detection) 17:26:00 INFO - PROCESS | 27007 | RSS: Main: 161120256 17:26:00 INFO - PROCESS | 27007 | 17:26:02 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53788/tests/kraken/driver.html?testName=audio-dft) 17:26:03 INFO - PROCESS | 27007 | RSS: Main: 159608832 17:26:03 INFO - PROCESS | 27007 | 17:26:06 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53788/tests/kraken/driver.html?testName=audio-fft) 17:26:06 INFO - PROCESS | 27007 | RSS: Main: 156270592 17:26:06 INFO - PROCESS | 27007 | 17:26:08 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53788/tests/kraken/driver.html?testName=audio-oscillator) 17:26:09 INFO - PROCESS | 27007 | RSS: Main: 157274112 17:26:09 INFO - PROCESS | 27007 | 17:26:11 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53788/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:26:12 INFO - PROCESS | 27007 | RSS: Main: 157364224 17:26:12 INFO - PROCESS | 27007 | 17:26:15 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53788/tests/kraken/driver.html?testName=imaging-darkroom) 17:26:15 INFO - PROCESS | 27007 | RSS: Main: 161591296 17:26:15 INFO - PROCESS | 27007 | 17:26:19 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53788/tests/kraken/driver.html?testName=imaging-desaturate) 17:26:20 INFO - PROCESS | 27007 | RSS: Main: 160534528 17:26:20 INFO - PROCESS | 27007 | 17:26:22 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53788/tests/kraken/driver.html?testName=json-parse-financial) 17:26:23 INFO - PROCESS | 27007 | RSS: Main: 160432128 17:26:23 INFO - PROCESS | 27007 | 17:26:25 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53788/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:26:25 INFO - PROCESS | 27007 | RSS: Main: 160227328 17:26:25 INFO - PROCESS | 27007 | 17:26:27 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:26:28 INFO - PROCESS | 27007 | RSS: Main: 160169984 17:26:28 INFO - PROCESS | 27007 | 17:26:30 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:26:30 INFO - PROCESS | 27007 | RSS: Main: 160432128 17:26:30 INFO - PROCESS | 27007 | 17:26:33 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:26:34 INFO - PROCESS | 27007 | RSS: Main: 160526336 17:26:34 INFO - PROCESS | 27007 | 17:26:37 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:26:37 INFO - PROCESS | 27007 | RSS: Main: 160645120 17:26:37 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | Cycle 1(1): loaded http://localhost:53788/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53788/tests/kraken/driver.html?testName=ai-astar) 17:26:39 INFO - PROCESS | 27007 | RSS: Main: 162480128 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | __start_tp_report 17:26:39 INFO - PROCESS | 27007 | _x_x_mozilla_page_load 17:26:39 INFO - PROCESS | 27007 | _x_x_mozilla_page_load_details 17:26:39 INFO - PROCESS | 27007 | |i|pagename|runs| 17:26:39 INFO - PROCESS | 27007 | |0;ai-astar;105;113;113;111;112;112;111;111;112;111 17:26:39 INFO - PROCESS | 27007 | |1;audio-beat-detection;149;119;124;179;121;123;120;121;138;125 17:26:39 INFO - PROCESS | 27007 | |2;audio-dft;163;164;164;166;163;168;165;163;162;164 17:26:39 INFO - PROCESS | 27007 | |3;audio-fft;76;77;77;118;77;76;76;76;94;77 17:26:39 INFO - PROCESS | 27007 | |4;audio-oscillator;84;85;115;84;85;85;97;84;83;102 17:26:39 INFO - PROCESS | 27007 | |5;imaging-gaussian-blur;118;118;121;118;118;118;118;117;117;118 17:26:39 INFO - PROCESS | 27007 | |6;imaging-darkroom;148;148;148;147;148;147;148;147;148;148 17:26:39 INFO - PROCESS | 27007 | |7;imaging-desaturate;96;96;96;96;96;96;96;96;96;96 17:26:39 INFO - PROCESS | 27007 | |8;json-parse-financial;58;59;55;57;56;59;58;58;58;57 17:26:39 INFO - PROCESS | 27007 | |9;json-stringify-tinderbox;66;67;67;66;67;66;66;68;67;67 17:26:39 INFO - PROCESS | 27007 | |10;stanford-crypto-aes;82;84;82;83;83;89;83;90;83;84 17:26:39 INFO - PROCESS | 27007 | |11;stanford-crypto-ccm;133;141;130;127;137;129;127;126;172;127 17:26:39 INFO - PROCESS | 27007 | |12;stanford-crypto-pbkdf2;169;169;173;168;172;173;172;172;182;174 17:26:39 INFO - PROCESS | 27007 | |13;stanford-crypto-sha256-iterative;63;63;63;63;64;63;66;66;66;67 17:26:39 INFO - PROCESS | 27007 | __end_tp_report 17:26:39 INFO - PROCESS | 27007 | __start_cc_report 17:26:39 INFO - PROCESS | 27007 | _x_x_mozilla_cycle_collect,720 17:26:39 INFO - PROCESS | 27007 | __end_cc_report 17:26:39 INFO - PROCESS | 27007 | __startTimestamp1483061199761__endTimestamp 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | ------- Summary: start ------- 17:26:39 INFO - PROCESS | 27007 | Number of tests: 14 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:2.28 (2.0%) stddev-sans-first:0.83 17:26:39 INFO - PROCESS | 27007 | Values: 105.0 113.0 113.0 111.0 112.0 112.0 111.0 111.0 112.0 111.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:124.50 stddev:19.10 (15.3%) stddev-sans-first:19.23 17:26:39 INFO - PROCESS | 27007 | Values: 149.0 119.0 124.0 179.0 121.0 123.0 120.0 121.0 138.0 125.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#2] audio-dft Cycles:10 Average:164.20 Median:164.00 stddev:1.75 (1.1%) stddev-sans-first:1.80 17:26:39 INFO - PROCESS | 27007 | Values: 163.0 164.0 164.0 166.0 163.0 168.0 165.0 163.0 162.0 164.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#3] audio-fft Cycles:10 Average:82.40 Median:77.00 stddev:13.67 (17.8%) stddev-sans-first:14.30 17:26:39 INFO - PROCESS | 27007 | Values: 76.0 77.0 77.0 118.0 77.0 76.0 76.0 76.0 94.0 77.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#4] audio-oscillator Cycles:10 Average:90.40 Median:84.50 stddev:10.79 (12.8%) stddev-sans-first:11.20 17:26:39 INFO - PROCESS | 27007 | Values: 84.0 85.0 115.0 84.0 85.0 85.0 97.0 84.0 83.0 102.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:1.10 (0.9%) stddev-sans-first:1.17 17:26:39 INFO - PROCESS | 27007 | Values: 118.0 118.0 121.0 118.0 118.0 118.0 118.0 117.0 117.0 118.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#6] imaging-darkroom Cycles:10 Average:147.70 Median:148.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 17:26:39 INFO - PROCESS | 27007 | Values: 148.0 148.0 148.0 147.0 148.0 147.0 148.0 147.0 148.0 148.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:96.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 17:26:39 INFO - PROCESS | 27007 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:1.27 (2.2%) stddev-sans-first:1.33 17:26:39 INFO - PROCESS | 27007 | Values: 58.0 59.0 55.0 57.0 56.0 59.0 58.0 58.0 58.0 57.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#9] json-stringify-tinderbox Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.67 17:26:39 INFO - PROCESS | 27007 | Values: 66.0 67.0 67.0 66.0 67.0 66.0 66.0 68.0 67.0 67.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:83.50 stddev:2.83 (3.4%) stddev-sans-first:2.88 17:26:39 INFO - PROCESS | 27007 | Values: 82.0 84.0 82.0 83.0 83.0 89.0 83.0 90.0 83.0 84.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#11] stanford-crypto-ccm Cycles:10 Average:134.90 Median:131.50 stddev:13.93 (10.6%) stddev-sans-first:14.76 17:26:39 INFO - PROCESS | 27007 | Values: 133.0 141.0 130.0 127.0 137.0 129.0 127.0 126.0 172.0 127.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:172.50 stddev:3.92 (2.3%) stddev-sans-first:3.96 17:26:39 INFO - PROCESS | 27007 | Values: 169.0 169.0 173.0 168.0 172.0 173.0 172.0 172.0 182.0 174.0 17:26:39 INFO - PROCESS | 27007 | 17:26:39 INFO - PROCESS | 27007 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.65 (2.5%) stddev-sans-first:1.67 17:26:39 INFO - PROCESS | 27007 | Values: 63.0 63.0 63.0 63.0 64.0 63.0 66.0 66.0 66.0 67.0 17:26:39 INFO - PROCESS | 27007 | -------- Summary: end -------- 17:26:39 INFO - PROCESS | 27007 | 17:26:40 INFO - TEST-INFO | 27007: exit 0 17:26:40 INFO - TEST-OK | kraken | took 62422ms 17:26:40 INFO - SUITE-END | took 1177s 17:26:40 INFO - Completed test suite (00:19:37) 17:26:40 INFO - javascript benchmark 17:26:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7024.789410936337, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3213.325201707641, 3251.8536665850047, 3266.689664481875, 3272.492763621638, 3274.901752947412, 5279.049771041211, 5518.862028449291, 5557.972210138949, 5566.888662226753, 5569.415211402805, 5328.120860058096, 5440.483154100354, 5446.019716451037, 5461.69960652164, 5556.333240011194, 4865.440474345452, 4887.364642596457, 4918.508149185071, 4981.227909674007, 5018.523240292185, 4842.322074909498, 4983.352164218646, 4986.75066246688, 4991.875203119915, 5016.197408414654, 4818.879528011793, 5044.848654540369, 5058.873528161788, 5074.594032477393, 5147.253648221005, 4869.82955596556, 4893.7063776173545, 4896.412430508326, 4944.456109827916, 5025.899482010339, 4959.20652695567, 4963.60291176705, 4968.776405061781, 4983.700977941335, 5013.323201367805, 5438.592105592096, 5443.8094666686875, 5459.426760190163, 5465.754041068161, 5476.205950137248, 5615.298087739033, 5657.830265092054, 5813.331466881321, 5823.796167134173, 5841.386654401312, 5885.470307672287, 5922.763089476416, 5950.9702451487465, 5954.0, 6013.188219590387, 5446.046941785211, 5475.233467314579, 5513.889722205574, 5517.944820551824, 5521.5306698931045, 5818.76724930998, 5886.0, 5932.199153114274, 5977.103434484862, 6074.058520929263, 5747.48272655463, 5760.827175184667, 5769.163471296639, 5798.0, 5830.212921255661, 5803.825885223366, 5810.1865738796605, 5845.941540584539, 5862.413758624189, 5868.149118377812, 5583.804566840141, 5594.496495315441, 5598.804041858515, 5725.4274572543245, 5733.77064917407, 5674.432556744293, 5687.544996400278, 5689.68706721126, 5784.161296611967, 5787.189793428924, 4338.522762496123, 4361.9127617447475, 4371.868843934623, 4392.670549708785, 4394.209042372339, 3905.3556163232747, 3911.6700321890694, 3912.6283003115227, 3929.0766669832565, 3940.9605903941165, 5385.0, 5419.918701219485, 5494.587905906993, 5498.120300751861, 5609.270794796732, 5782.537397008229, 5795.7391917362975, 5806.970965145148, 5838.620489668158, 5876.353601103875, 5576.86057848557, 5592.245046918695, 5606.327240731057, 5631.014572449805, 5640.661560306395, 4660.184467718203, 4747.216709242936, 4841.443234028132, 4873.220284754422, 4921.729304888194, 6106.694665266719, 6180.6291622502795, 6185.072239164071, 6186.566940314135, 6188.690565471708, 5898.056310990221, 5899.941000590111, 5906.291245050621, 5927.614705044159, 5941.524678025661, 5947.1079338099635, 5989.251343582052, 6169.475594574512, 6173.722182501709, 6181.84545386369, 5745.942540574708, 5781.161731548986, 5791.739371728198, 5840.240768700041, 5879.588428809942, 5861.794837180678, 5924.4667979882015, 5928.288605367384, 5929.733162007634, 5936.851578710394, 5581.441855814549, 5739.426057394394, 5796.739146738322, 5807.738651760596, 5808.796692115756, 5605.831825045255, 5668.149777533403, 5673.801416950387, 5686.346070201815, 5793.36273009977, 5672.602917795714, 5711.228984087095, 5712.6286791358425, 5718.571107166896, 5728.656280623176, 5601.187827764869, 5634.6337488063145, 5710.543156547383, 5774.740136693943, 5777.306723193075, 5584.972075139598, 5613.522850557748, 5622.0, 5652.152177173457, 5706.3152421709665], "value": 5381.804446665902, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.3319234360878, 557.2171099605056, 565.0055901613165, 574.635106707241, 576.4437317988142, 40493.392599111, 42483.78758106209, 42710.0, 46254.306185407164, 46316.3052554212, 43731.562684373144, 44129.77935110328, 44501.332480012825, 44542.33186502205, 44657.330140047925, 8820.0, 8955.373123881298, 8967.461952282883, 8977.640894364251, 10092.293539452241, 10389.0, 10389.064984151424, 10389.740256493611, 10432.843507347357, 10448.373097614129, 10530.0, 10568.577256909715, 10573.154147668207, 10579.153667706603, 10599.682009539687, 10395.064444199983, 10426.63506777263, 10479.318844275174, 10480.423576703279, 10485.685429437053, 10233.334833235816, 10337.483125843677, 10342.379457232515, 10363.79272414555, 10365.792684146274, 36954.44568331477, 36957.81521092404, 37148.25703485942, 37391.0, 37436.251274974624, 3976.642102210786, 4012.198513926967, 4061.8375264989363, 4103.425520427142, 4115.753054816721, 27979.440411191663, 28610.0, 29017.274568135756, 29191.562126567907, 29565.260868478246, 4210.9578904210875, 4340.435743353375, 4395.692301538894, 4397.406350142722, 4420.248557745191, 337752.0, 340936.0, 342874.0, 343150.9999999975, 344404.0, 51032.234516482284, 51557.74221128871, 51624.99999999925, 51786.0, 51821.0, 53539.0, 53610.463895361325, 53696.0, 53722.7313863436, 53809.730951344995, 14868.553943381716, 15189.240537973279, 15346.155961422006, 15356.539303820904, 15358.078515289119, 11003.61487347939, 11059.336439813636, 11251.43742812856, 11774.529018839321, 12022.098342624338, 16449.83550164483, 16476.258568364454, 16480.917595411946, 16486.587835304214, 16655.0, 6552.50856185788, 6723.125993620798, 6740.764073257413, 6903.0, 6905.792826215222, 895.0, 918.6574235698941, 924.713338864954, 928.1553786054793, 950.538988590533, 29906.80372785108, 30007.0, 30136.397272054874, 30264.0, 31942.840285798888, 1052.1109662335314, 1083.4636854756789, 1087.853139826129, 1090.5201711247025, 1091.3942761767296, 15648.530544083696, 16031.358745650277, 16211.918940405223, 16329.91835040841, 16578.253978570992, 492801.0, 498871.0, 499231.0, 505554.0, 509150.0, 58299.70850145722, 58538.0, 58674.11988820171, 58999.41000589939, 59029.11456328158, 1346.548906116462, 1374.5670113914084, 1380.7376598446263, 1389.770687836516, 1392.9164250145025, 28670.856645716638, 29290.121296361143, 29616.703832961393, 29681.109566713032, 30249.848750756104, 49515.50484495253, 49609.50390496049, 49999.75000124976, 50081.0, 50170.0, 389536.0, 390036.0, 390097.0, 391309.0, 391937.0, 24851.0, 25255.242342729012, 25355.873220633777, 26053.218403447925, 27022.594661080493, 41394.0, 41491.58508414877, 41886.79056604698, 43011.13977720491, 43091.138177236906, 34702.82648586741, 34714.47928281027, 35349.29301414009, 35425.46861797023, 35919.820400898876, 34697.1014927468, 34917.650823492455, 34991.82504087463, 35077.64922350744, 35427.645723543465, 32424.675753242165, 33231.33537329288, 34683.30633387268, 35401.645983539835, 35847.283054338244, 29810.552841707824, 31570.210744731565, 32019.67980320167, 32122.51816222712, 32173.517397239528, 24504.87747561251, 24664.630030549197, 24869.626955596043, 25165.370865728502, 25262.115825946003], "value": 19239.585080857785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9566647668756, 400.8817398867334, 411.2535747618073, 414.8465067924868, 422.11988005009556, 18380.540486487844, 18528.72206916894, 18926.716099258523, 19162.329318473858, 19167.80832191676, 17882.28470861167, 18114.27542898286, 18995.0, 19507.317243896465, 19744.70382944257, 5545.140503222007, 5607.551395888339, 5804.535637149039, 5818.301803783552, 5837.328707198662, 5894.469497745202, 5908.527317814586, 5920.763169473216, 5928.347881733006, 5946.108083787445, 5755.9424405756035, 6380.808575742735, 6436.517261205405, 6460.903086453707, 6478.838029049264, 5864.325602555718, 6025.367336429656, 6374.713137908782, 6423.550351475398, 6430.839229019265, 5659.943400565983, 5662.235598194274, 5692.715364231772, 5694.658320500742, 5726.885462290731, 16064.678706425806, 20742.688859667116, 21567.1373145074, 21677.132914683392, 22048.779512204834, 2577.510273048115, 2633.947321053568, 2668.426288347995, 2760.6687197536426, 2768.1418760184406, 8946.105389461067, 9497.90502094984, 9728.73526441558, 10042.849357259647, 10308.0, 1990.8208261256411, 2001.4996250937265, 2012.8087831655969, 2013.134352228541, 2017.3342796876996, 75231.0, 77361.61319193424, 78846.6057669708, 79670.20329796686, 80004.19995800026, 10208.948955255177, 10362.585496580052, 10473.84289235662, 10703.250772445854, 11058.336499810037, 13467.730645387037, 13544.93227533856, 13561.0, 13622.318884055956, 13630.591082267549, 7432.888506672404, 7596.924030759622, 7652.365648024617, 7895.565743884142, 7938.483998540077, 7283.490155689157, 7339.779806605704, 7364.889526657104, 7530.246975302426, 7580.772576822704, 4669.673122881433, 4704.435467743892, 4707.7881495332795, 4715.693479923794, 4882.8779280517565, 1902.5243689077731, 1946.240966023252, 1977.2980591889673, 1991.601679664073, 1992.7608686957658, 898.4025622960658, 911.9908800911905, 918.6279556779515, 922.1654402765508, 925.5696101312922, 17069.40257090996, 17317.7402338965, 17526.386576469762, 17821.643567128584, 18344.633107337777, 915.3729695158704, 926.4070994563485, 934.3506263147048, 937.4656445825814, 940.8588711693301, 10364.896351036545, 10410.791784164427, 10513.52689128991, 10556.683299501028, 10601.0, 416624.0, 418361.0, 421348.0, 423569.0, 425336.0, 81239.28226818443, 82360.58819705863, 82551.58724206341, 86423.56788216019, 86732.56633716918, 996.9202463802878, 1008.7679833638305, 1026.7587117027492, 1027.3630349183554, 1027.825269704152, 15921.283542240395, 17058.0, 17229.56926076858, 17361.82638173602, 17381.56546086358, 35963.46054809232, 37256.44115338218, 40777.796111019255, 41537.37693934654, 41799.37300940428, 52358.0, 53301.46698532965, 53415.46584534105, 54363.0, 54815.0, 8331.583420828812, 8499.065102838806, 8545.401821872409, 8737.694180703898, 8839.51382673959, 9644.469554174588, 9647.0, 10675.839862401916, 12655.0, 12882.355882205664, 10769.9461502692, 11188.552457901918, 11436.485358158736, 11473.254238474472, 11704.297742135499, 6827.283135270725, 7392.8891066635115, 11221.102311814871, 11526.884731152917, 11721.824172637247, 6660.1341825564305, 8923.107689231285, 10900.0, 11595.304281743123, 11656.592019279624, 7385.556866588022, 7391.6304184791625, 7555.433342499225, 7708.537487750753, 7783.688652453839, 6012.849678758066, 11880.524779008743, 12035.518579256732, 12199.69500762488, 12222.144449888423], "value": 8848.822573672873, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.9118657287277, 1359.0962010263177, 1359.8844098251654, 1364.5565191312826, 1375.2229990055619, 7550.6224688765615, 7722.8455430891345, 7976.2821346078845, 8034.0760812506505, 8036.116027237006, 7976.0, 8119.959400203005, 8145.34837213023, 8146.551939643317, 8158.836823263531, 2264.275431861801, 2281.589313923493, 2299.8505097168713, 2300.447892505795, 2309.180241014441, 2246.427161073932, 2284.0, 2302.1827251325703, 2342.4612339162004, 2359.0563774490256, 2287.5996700577416, 2297.896594653236, 2303.0442366417915, 2303.9654405183937, 2305.3890718959487, 2185.4864106934856, 2199.3511913985412, 2202.0751284460407, 2202.9315781845794, 2206.0, 2231.308294428716, 2245.764194759543, 2286.8513546619415, 2291.1522736587403, 2308.1690591387087, 7779.688812447495, 7915.287624113857, 7927.048754149481, 7938.404619653491, 7945.562994035326, 4813.55593322002, 4959.206526955706, 4967.751612419364, 4974.751262436864, 4978.228374601942, 3264.1778394205644, 3335.966640333614, 3351.8491667875005, 3371.3088816792497, 3380.8985730428126, 1351.202790353696, 1413.766728489798, 1421.7938398932097, 1457.147568672328, 1524.8932574719663, 10549.789004219949, 10614.946925265323, 10689.358638481715, 10718.464076796128, 10758.946205269001, 3903.882883513499, 3956.188981258835, 4115.0741083256025, 4122.773247471418, 4130.524989626231, 8417.410781245255, 8431.28334091609, 8437.240648341678, 8443.282321002784, 8467.830643387098, 4698.130845793539, 4892.657513974059, 4964.627652926045, 4971.080350135237, 4978.253262010728, 4626.421697287839, 4627.421572303462, 4658.790354434059, 4678.391809064868, 4680.081166721696, 3459.9654003460146, 3543.1319326765106, 3550.573931128281, 3553.2715793262837, 3617.746757726934, 1248.450681700049, 1281.9679508012375, 1290.9419076141596, 1291.547958214636, 1292.7414517096618, 908.9909100909039, 917.3349321741684, 930.1767935377104, 933.3419938943028, 957.2963871554402, 5323.467653234646, 5486.588505862076, 5640.915386269209, 5733.0, 5750.396208398141, 934.9158575728217, 961.6393852305386, 966.415318732157, 978.9804203916025, 1008.3344992305043, 3670.8348124333934, 3731.9253614927547, 3794.4877441545036, 3855.363864962306, 3882.728209025341, 9004.099590040943, 9425.340226184238, 9429.76425589352, 9435.952820235854, 9468.763280917894, 9350.205232555172, 9368.110029547182, 9402.670906518239, 9415.246780257563, 9418.246540276761, 7218.3503484686635, 7248.4563657725885, 7286.380657644159, 7452.217517160729, 7456.51532650376, 5352.40408401917, 5389.003034438641, 5407.0, 5425.077736784835, 5605.271314729059, 8703.433381991303, 8717.084706106021, 8762.211400973943, 8772.692955746517, 8803.723460098121, 8435.072142064491, 8672.306215502871, 8675.0, 8844.46933184011, 8854.690085846965, 7044.823879403056, 7182.0, 7200.351968322876, 7212.350888420067, 7225.24134965821, 6380.649064301508, 7367.668454919434, 7429.03422555086, 7466.440016998638, 7515.286047825448, 6212.844678883064, 6464.709088090953, 6533.477321814338, 6625.734970601122, 6655.667216639052, 6489.383508566679, 6710.16122984627, 6729.192496900403, 6736.494762892705, 6770.627615481196, 5920.319163296276, 5951.55363347742, 5977.701114944149, 6031.969840150771, 6069.9089513656445, 4644.256918892961, 4678.64319347399, 4707.505711900201, 4724.6692731508465, 4784.760761961818, 4549.522300158435, 4624.560666736655, 4625.213713668617, 4637.0, 4696.882577935579], "value": 4284.005293500925, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.1293898709294, 402.1735333888859, 408.90390758171844, 409.1714278585863, 415.71731222768506, 5313.8937221255555, 5405.0, 5425.724954635657, 5427.674339539625, 5434.673919564819, 5211.86970325742, 5278.868028299295, 5290.68255904646, 5302.549283310923, 5379.81170659027, 4272.145570885819, 4273.764942928137, 4292.356146578023, 4294.914101717972, 4309.095090031082, 4301.3547967804925, 4316.007318316789, 4331.7400955942585, 4332.350147477872, 4344.847930322439, 4164.979175104136, 4202.936955945664, 4250.61744443, 4303.505096913864, 4327.848525301615, 4260.723053001545, 4262.0, 4262.552431994627, 4266.210751011057, 4288.699791014615, 3854.8265328060306, 3855.132595166065, 3856.267309211241, 3868.3810590305698, 3880.7671539707426, 5146.562542183919, 5189.818356357509, 5334.786608535654, 5335.3864305604575, 5355.892882142374, 4827.630993950481, 4877.365942427497, 4916.975415122902, 4926.162552366106, 4959.752012399402, 5589.692566908819, 5610.298712660918, 5625.1843482695185, 5635.830925072254, 5672.893785711788, 4130.339145736698, 4243.0, 4269.633717210494, 4279.87160385189, 4297.742135471851, 5615.0, 5763.567732420085, 5787.942120578825, 5794.942050579524, 5802.912956305659, 4114.189756507128, 4155.958440415558, 4161.708680392343, 4161.854335098257, 4162.875113746592, 4186.099988502487, 4204.159168166379, 4211.557786432441, 4216.789160542022, 4226.5773422657485, 4508.819647214074, 4768.47546769855, 4804.927926081112, 4809.0, 4816.927746083811, 5598.692071936082, 5606.887862242814, 5612.242347283064, 5612.652963288863, 5617.662940223599, 1413.3957957718064, 1413.9607388569393, 1417.6597616571948, 1420.88632909367, 1425.0, 953.5899563187758, 987.6247026130168, 988.7083310423442, 993.9801203976025, 1013.6452241715488, 5282.286891269706, 5286.414075777251, 5322.8935421292135, 5361.579181516901, 5369.201317558662, 5566.888662226733, 5601.075822489243, 5608.439156084359, 5628.183913332544, 5667.688277144714, 5054.595632349403, 5189.273501709764, 5266.420693723688, 5281.551068159172, 5291.6872748709675, 2912.927176820554, 2948.142670117812, 2959.186223788475, 2961.4225226080707, 3020.5016172331757, 5841.0, 5845.025382739693, 5848.976429124886, 5854.297484301911, 5874.706264686749, 5588.413216612278, 5878.823635290948, 5906.468417842415, 5937.079752638261, 5949.196858424058, 6108.816735497942, 6147.938520614826, 6168.414000669929, 6169.321374648785, 6200.596961197598, 5661.858453538694, 5817.389174136824, 5835.4748072673665, 5869.941300586939, 5874.265716785402, 5745.971270143623, 5754.3382511010095, 5761.625494342854, 5772.884542309214, 5780.0, 5852.385499522489, 6035.0, 6086.013661052018, 6092.9086063709965, 6106.267247930276, 4158.459400278066, 4186.790660467025, 4196.412502249748, 4215.704900656925, 4218.789060546899, 5682.289713785777, 5862.208601838868, 5874.0, 5896.6756828374855, 5923.289205295392, 5678.602497825278, 5679.602427830012, 5680.2047713321, 5704.372519022987, 5725.828225153252, 5659.528522584075, 5674.609720618475, 5679.829605111853, 5682.6306290091015, 5708.828735137952, 5542.2795036644975, 5612.831615051555, 5650.802221922213, 5671.631343962629, 5685.374608793112, 5721.227634269487, 5735.3977832327, 5777.768889244383, 5778.393268706725, 5796.710164491843, 5554.611347163209, 5580.525655319343, 5590.916136257878, 5607.018771715015, 5619.831405057855], "value": 4353.3347819117935, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.3372348025036, 768.2317682317682, 793.992060079399, 817.6811043692959, 826.1655727715006, 14694.41222351106, 15180.54458366248, 15490.690186196269, 15581.610459738513, 15834.208289585504, 13207.603771886836, 13657.112287701317, 13826.792598111037, 13829.723405531908, 13962.0, 812.5896422306727, 834.5493433545878, 836.8912041434605, 837.7779888329567, 838.714836955435, 830.3315830756238, 834.6160766047625, 836.8284501677172, 837.2297486312576, 838.651959436833, 854.9914500855006, 856.9485830850138, 864.5720368417642, 865.1651156633847, 866.1079088538784, 857.7855536115972, 858.4248553469191, 862.2369203255101, 863.223099210709, 866.4671227195292, 633.2116514938931, 637.6939069246741, 638.0270088115615, 638.4190386748083, 639.9104125422444, 12593.622191334274, 12595.181313214613, 12645.557405490763, 12653.36733163338, 12748.681282968, 10827.29622574538, 11177.944110279397, 11183.776324473547, 11196.0, 11514.078873690169, 11036.392998385085, 11071.446427678664, 11122.721931951684, 11145.498452569655, 11157.66527004191, 3673.5775385830702, 3696.685781708558, 3764.4353346997896, 3778.03660066684, 3789.3179227738997, 73964.63017684931, 74573.0, 75335.0, 76082.0, 76811.0, 9090.86363704545, 9198.218151457068, 9238.44569325829, 9248.676296329597, 9258.814823703487, 17823.910880445514, 17879.106044697714, 17904.0, 17910.641787164444, 17921.193546290448, 7286.635668216674, 7740.4194685398825, 7843.803904902309, 8156.714514992065, 8167.673293068211, 4354.477462704433, 4517.774111294488, 4539.16025535277, 4595.6528410975025, 4607.0, 3214.7428205743486, 3300.389427955788, 3315.1049216711835, 3316.4859446785786, 3319.0872510059926, 580.4920694392407, 581.1573218832625, 591.8342863998088, 593.2643522032648, 594.5897330841706, 684.4319215051495, 684.8835697931364, 695.1762161838238, 697.4803771190495, 699.6606645776794, 8791.164839340252, 8833.60248788806, 9007.0, 9057.18485336323, 9164.770880728036, 647.8769033883547, 654.738104758091, 661.9371159739817, 662.6885363878969, 670.4267850987433, 4184.832606695698, 4367.541408152162, 4412.911741765147, 4416.580424859633, 4431.689781715249, 80861.0, 85397.4380768578, 86077.56961215154, 86140.56929715311, 86519.0, 71911.2808871915, 73811.26188738151, 73883.63058184783, 74228.0, 74777.25222747812, 64833.35166648367, 64872.35127648663, 65092.34907650958, 65199.67400162969, 65654.0, 8275.50346979183, 8474.0, 8556.657733690832, 8569.271611913056, 8669.263112635244, 43574.782126089165, 44145.11709765851, 45461.31808022948, 45536.861578460805, 46315.66318062781, 48078.75960620175, 48692.0, 50118.49881501284, 53120.359955005624, 55238.0, 12683.365831708194, 12846.614601561969, 12867.420966056363, 12881.871181288067, 12889.548865789653, 15290.694186116438, 15320.31058602388, 15497.612559686098, 15757.133357665438, 16026.759598605797, 13946.511872084428, 14121.364538595582, 14343.56969290923, 14580.416783328548, 14600.12399256048, 14064.0, 14648.926755366156, 14667.633309167357, 14980.0, 15640.374385024472, 13536.526221582197, 13707.794383084061, 14249.786253206003, 14252.0, 14610.707785844012, 11267.718307042389, 11553.24903881245, 11630.825537616773, 11919.46362413676, 12049.81925271104, 11148.944255278671, 11256.718582035515, 11511.0, 11571.594994175164, 11579.247348922294], "value": 7031.927646106448, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 113.0, 111.0, 112.0, 112.0, 111.0, 111.0, 112.0, 111.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 119.0, 124.0, 179.0, 121.0, 123.0, 120.0, 121.0, 138.0, 125.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 166.0, 163.0, 168.0, 165.0, 163.0, 162.0, 164.0], "value": 164.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 77.0, 118.0, 77.0, 76.0, 76.0, 76.0, 94.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 115.0, 84.0, 85.0, 85.0, 97.0, 84.0, 83.0, 102.0], "value": 90.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 121.0, 118.0, 118.0, 118.0, 118.0, 117.0, 117.0, 118.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 148.0, 147.0, 148.0, 147.0, 148.0, 147.0, 148.0, 148.0], "value": 147.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 55.0, 57.0, 56.0, 59.0, 58.0, 58.0, 58.0, 57.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 68.0, 67.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 83.0, 83.0, 89.0, 83.0, 90.0, 83.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 141.0, 130.0, 127.0, 137.0, 129.0, 127.0, 126.0, 172.0, 127.0], "value": 134.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 173.0, 168.0, 172.0, 173.0, 172.0, 172.0, 182.0, 174.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 66.0, 66.0, 66.0, 67.0], "value": 64.4, "unit": "score"}]}]} 17:26:41 INFO - Return code: 0 17:26:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:26:41 ERROR - # TBPL SUCCESS # 17:26:41 INFO - Running post-action listener: _resource_record_post_action 17:26:41 INFO - [mozharness: 2016-12-30 01:26:41.070969Z] Finished run-tests step (success) 17:26:41 INFO - Running post-run listener: _resource_record_post_run 17:26:41 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 414711808; Read time: 8; Write time: 547644 17:26:41 INFO - TinderboxPrint: CPU usage
13.4% 17:26:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 17:26:41 INFO - TinderboxPrint: I/O write bytes / time
414,711,808 / 547,644 17:26:41 INFO - TinderboxPrint: CPU idle
8,251.1 (86.5%) 17:26:41 INFO - TinderboxPrint: CPU user
1,217.8 (12.8%) 17:26:41 INFO - TinderboxPrint: Swap in / out
0 / 0 17:26:41 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 110030848; Read time: 0; Write time: 128096 17:26:41 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 292352000; Read time: 0; Write time: 416964 17:26:41 INFO - Running post-run listener: _upload_blobber_files 17:26:41 INFO - Blob upload gear active. 17:26:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:26:41 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 17:26:41 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:26:41 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:26:42 INFO - (blobuploader) - INFO - Open directory for files ... 17:26:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:26:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:26:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:42 INFO - (blobuploader) - INFO - Done attempting. 17:26:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:26:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:43 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:26:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:43 INFO - (blobuploader) - INFO - Done attempting. 17:26:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:26:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:26:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:47 INFO - (blobuploader) - INFO - Done attempting. 17:26:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:26:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:26:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:26:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:26:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:26:50 INFO - (blobuploader) - INFO - Done attempting. 17:26:50 INFO - (blobuploader) - INFO - Iteration through files over. 17:26:50 INFO - Return code: 0 17:26:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:26:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:26:50 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3555858ccfeac3292935e7fa2222280050fa6282a7f8e5fb9e309b3e03d482d6aee8278a4d08e64afddf090855873ce79e0d4fb3e653bc79fdd02cdf693d30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc36db1491b411494facd52003dd1846a64f68d444e727db4801da4ff168c7a4c169351cd2d3af0f02054945638b470798461dba2f2098d55634933b34a739b9", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5665f610ab806d0a27ef81597262a6439a14919468ddd67789e88d9ab167a766451bdb4884aaee3ca4b6fe37c62d7c1800231e2f5ca3ad41940ffd7e6b758ba5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/641b8ab0c006e232d0a01ede3647fae2628f8f6f1db0272ec3d5dd2cd507ea343e8caaa6512ceb51563b616cb73a304f0aefa34b4571d7750affa20dd31574d5"} 17:26:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:26:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:26:50 INFO - Contents: 17:26:50 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3555858ccfeac3292935e7fa2222280050fa6282a7f8e5fb9e309b3e03d482d6aee8278a4d08e64afddf090855873ce79e0d4fb3e653bc79fdd02cdf693d30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc36db1491b411494facd52003dd1846a64f68d444e727db4801da4ff168c7a4c169351cd2d3af0f02054945638b470798461dba2f2098d55634933b34a739b9", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5665f610ab806d0a27ef81597262a6439a14919468ddd67789e88d9ab167a766451bdb4884aaee3ca4b6fe37c62d7c1800231e2f5ca3ad41940ffd7e6b758ba5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/641b8ab0c006e232d0a01ede3647fae2628f8f6f1db0272ec3d5dd2cd507ea343e8caaa6512ceb51563b616cb73a304f0aefa34b4571d7750affa20dd31574d5"} 17:26:50 INFO - Running post-run listener: copy_logs_to_upload_dir 17:26:50 INFO - Copying logs to upload dir... 17:26:50 INFO - mkdir: /builds/slave/test/build/upload/logs 17:26:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.758578 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 42 secs) (at 2016-12-29 17:26:50.472991) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 17:26:50.476734) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3555858ccfeac3292935e7fa2222280050fa6282a7f8e5fb9e309b3e03d482d6aee8278a4d08e64afddf090855873ce79e0d4fb3e653bc79fdd02cdf693d30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc36db1491b411494facd52003dd1846a64f68d444e727db4801da4ff168c7a4c169351cd2d3af0f02054945638b470798461dba2f2098d55634933b34a739b9", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5665f610ab806d0a27ef81597262a6439a14919468ddd67789e88d9ab167a766451bdb4884aaee3ca4b6fe37c62d7c1800231e2f5ca3ad41940ffd7e6b758ba5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/641b8ab0c006e232d0a01ede3647fae2628f8f6f1db0272ec3d5dd2cd507ea343e8caaa6512ceb51563b616cb73a304f0aefa34b4571d7750affa20dd31574d5"} build_url:https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011697 build_url: 'https://queue.taskcluster.net/v1/task/cxkGS1TPS3CEo13qt0bQfQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a3555858ccfeac3292935e7fa2222280050fa6282a7f8e5fb9e309b3e03d482d6aee8278a4d08e64afddf090855873ce79e0d4fb3e653bc79fdd02cdf693d30f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/dc36db1491b411494facd52003dd1846a64f68d444e727db4801da4ff168c7a4c169351cd2d3af0f02054945638b470798461dba2f2098d55634933b34a739b9", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/5665f610ab806d0a27ef81597262a6439a14919468ddd67789e88d9ab167a766451bdb4884aaee3ca4b6fe37c62d7c1800231e2f5ca3ad41940ffd7e6b758ba5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/641b8ab0c006e232d0a01ede3647fae2628f8f6f1db0272ec3d5dd2cd507ea343e8caaa6512ceb51563b616cb73a304f0aefa34b4571d7750affa20dd31574d5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-29 17:26:50.502088) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:26:50.502427) ========= 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=edae0fb5fee9fb758e8af6d3000002a4-1483058450.411061-1145883906 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004530 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-29 17:26:50.588295) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 17:26:50.588626) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-29 17:26:50.588937) ========= ========= Total master_lag: 0.19 =========