builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-009 starttime: 1482685916.94 results: success (0) buildid: 20161225073114 builduid: d5af0af2fe5840108f7a3c508f07bdca revision: 5ea0c495d3b2318287bffe1121e0e33d74427143 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:56.944850) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:56.945494) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:56.945790) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:56.961704) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:56.962049) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-25 09:11:57-- 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% 42.6M=0s 2016-12-25 09:11:57 (42.6 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135193 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.111047) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.111442) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021452 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.160315) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.160670) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5ea0c495d3b2318287bffe1121e0e33d74427143 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5ea0c495d3b2318287bffe1121e0e33d74427143 --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=17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-25 09:11:57,921 truncating revision to first 12 chars 2016-12-25 09:11:57,922 Setting DEBUG logging. 2016-12-25 09:11:57,922 attempt 1/10 2016-12-25 09:11:57,922 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5ea0c495d3b2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-25 09:11:58,239 unpacking tar archive at: mozilla-central-5ea0c495d3b2/testing/mozharness/ program finished with exit code 0 elapsedTime=0.658945 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.838140) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.838431) ========= script_repo_revision: 5ea0c495d3b2318287bffe1121e0e33d74427143 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.838851) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.839118) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 09:11:57.859254) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-25 09:11:57.860067) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:11:58 INFO - MultiFileLogger online at 20161225 09:11:58 in /builds/slave/test 09:11:58 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:11:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:11:58 INFO - {'append_to_log': False, 09:11:58 INFO - 'base_work_dir': '/builds/slave/test', 09:11:58 INFO - 'blob_upload_branch': 'Firefox', 09:11:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:11:58 INFO - 'branch': 'Firefox', 09:11:58 INFO - 'buildbot_json_path': 'buildprops.json', 09:11:58 INFO - 'config_files': ('talos/linux_config.py',), 09:11:58 INFO - 'default_actions': ('clobber', 09:11:58 INFO - 'read-buildbot-config', 09:11:58 INFO - 'download-and-extract', 09:11:58 INFO - 'populate-webroot', 09:11:58 INFO - 'create-virtualenv', 09:11:58 INFO - 'install', 09:11:58 INFO - 'run-tests'), 09:11:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:11:58 INFO - 'download_minidump_stackwalk': True, 09:11:58 INFO - 'download_symbols': 'ondemand', 09:11:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:11:58 INFO - 'tooltool.py': '/tools/tooltool.py', 09:11:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:11:58 INFO - '/tools/misc-python/virtualenv.py')}, 09:11:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:11:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:11:58 INFO - 'installer_path': 'installer.exe', 09:11:58 INFO - 'log_level': 'info', 09:11:58 INFO - 'log_name': 'talos', 09:11:58 INFO - 'log_to_console': True, 09:11:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:11:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:11:58 INFO - 'opt_config_files': (), 09:11:58 INFO - 'pip_index': False, 09:11:58 INFO - 'sps_profile': False, 09:11:58 INFO - 'sps_profile_interval': 0, 09:11:58 INFO - 'suite': 'dromaeojs-e10s', 09:11:58 INFO - 'system_bits': '32', 09:11:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:11:58 INFO - 'title': 'talos-linux64-ix-009', 09:11:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:11:58 INFO - 'use_talos_json': True, 09:11:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:11:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:11:58 INFO - 'work_dir': 'build'} 09:11:58 INFO - [mozharness: 2016-12-25 17:11:58.722099Z] Running clobber step. 09:11:58 INFO - Running pre-action listener: _resource_record_pre_action 09:11:58 INFO - Running main action method: clobber 09:11:58 INFO - rmtree: /builds/slave/test/build 09:11:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:11:59 INFO - Running post-action listener: _resource_record_post_action 09:11:59 INFO - [mozharness: 2016-12-25 17:11:59.177024Z] Finished clobber step (success) 09:11:59 INFO - [mozharness: 2016-12-25 17:11:59.177125Z] Running read-buildbot-config step. 09:11:59 INFO - Running pre-action listener: _resource_record_pre_action 09:11:59 INFO - Running main action method: read_buildbot_config 09:11:59 INFO - Using buildbot properties: 09:11:59 INFO - { 09:11:59 INFO - "project": "", 09:11:59 INFO - "product": "firefox", 09:11:59 INFO - "script_repo_revision": "production", 09:11:59 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 09:11:59 INFO - "repository": "", 09:11:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 09:11:59 INFO - "buildid": "20161225073114", 09:11:59 INFO - "pgo_build": "True", 09:11:59 INFO - "basedir": "/builds/slave/test", 09:11:59 INFO - "buildnumber": 498, 09:11:59 INFO - "slavename": "talos-linux64-ix-009", 09:11:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 09:11:59 INFO - "platform": "ubuntu64_hw", 09:11:59 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 09:11:59 INFO - "branch": "mozilla-central", 09:11:59 INFO - "repo_path": "mozilla-central", 09:11:59 INFO - "revision": "5ea0c495d3b2318287bffe1121e0e33d74427143", 09:11:59 INFO - "stage_platform": "linux64-pgo", 09:11:59 INFO - "builduid": "d5af0af2fe5840108f7a3c508f07bdca", 09:11:59 INFO - "slavebuilddir": "test" 09:11:59 INFO - } 09:11:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 09:11:59 INFO - Running post-action listener: _resource_record_post_action 09:11:59 INFO - [mozharness: 2016-12-25 17:11:59.179570Z] Finished read-buildbot-config step (success) 09:11:59 INFO - [mozharness: 2016-12-25 17:11:59.179660Z] Running download-and-extract step. 09:11:59 INFO - Running pre-action listener: _resource_record_pre_action 09:11:59 INFO - Running main action method: download_and_extract 09:11:59 INFO - mkdir: /builds/slave/test/build/tests 09:11:59 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')]} 09:11:59 INFO - trying https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/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 09:11:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/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 09:11:59 INFO - Downloaded 1361 bytes. 09:11:59 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 09:11:59 INFO - Using the following test package requirements: 09:11:59 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:59 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:11:59 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:11:59 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'jsshell-linux-x86_64.zip'], 09:11:59 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:11:59 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 09:11:59 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:11:59 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:11:59 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:11:59 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 09:11:59 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:11:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:11:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:11:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:11:59 INFO - Fetch https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:12:00 INFO - Expected file size: 21831882 09:12:00 INFO - Obtained file size: 21831882 09:12:04 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 09:12:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 09:12:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:12:04 INFO - Fetch https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 09:12:05 INFO - Expected file size: 21831882 09:12:05 INFO - Obtained file size: 21831882 09:12:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 09:12:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:12:09 INFO - Fetch https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 09:12:11 INFO - Expected file size: 14409776 09:12:11 INFO - Obtained file size: 14409776 09:12:11 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')]} 09:12:11 INFO - trying https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:12:11 INFO - Downloading https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/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 09:12:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/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 09:12:13 INFO - Downloaded 61372480 bytes. 09:12:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:12:13 INFO - mkdir: /builds/slave/test/properties 09:12:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:12:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:12:13 INFO - Contents: 09:12:13 INFO - build_url:https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 09:12:13 INFO - Running post-action listener: _resource_record_post_action 09:12:13 INFO - Running post-action listener: set_extra_try_arguments 09:12:13 INFO - [mozharness: 2016-12-25 17:12:13.725391Z] Finished download-and-extract step (success) 09:12:13 INFO - [mozharness: 2016-12-25 17:12:13.725617Z] Running populate-webroot step. 09:12:13 INFO - Running pre-action listener: _resource_record_pre_action 09:12:13 INFO - Running main action method: populate_webroot 09:12:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:12:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:12:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:12:13 INFO - '--tppagecycles', 09:12:13 INFO - '7'], 09:12:13 INFO - 'tests': ['tsvgm']}}, 09:12:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:12:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:12:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:12:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:12:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:12:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['damp', 'tps']}, 09:12:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'tests': ['damp', 'tps']}, 09:12:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['dromaeo_dom']}, 09:12:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:12:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['basic_compositor_video']}, 09:12:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:12:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['a11yr', 09:12:13 INFO - 'ts_paint', 09:12:13 INFO - 'tpaint', 09:12:13 INFO - 'sessionrestore', 09:12:13 INFO - 'sessionrestore_no_auto_restore', 09:12:13 INFO - 'tabpaint']}, 09:12:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:12:13 INFO - 'ts_paint', 09:12:13 INFO - 'tpaint', 09:12:13 INFO - 'sessionrestore', 09:12:13 INFO - 'sessionrestore_no_auto_restore', 09:12:13 INFO - 'tabpaint']}, 09:12:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:12:13 INFO - 'ts_paint', 09:12:13 INFO - 'tpaint', 09:12:13 INFO - 'sessionrestore', 09:12:13 INFO - 'sessionrestore_no_auto_restore', 09:12:13 INFO - 'tabpaint']}, 09:12:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:12:13 INFO - 'ts_paint', 09:12:13 INFO - 'tpaint', 09:12:13 INFO - 'sessionrestore', 09:12:13 INFO - 'sessionrestore_no_auto_restore', 09:12:13 INFO - 'tabpaint']}, 09:12:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['a11yr', 09:12:13 INFO - 'ts_paint', 09:12:13 INFO - 'tpaint', 09:12:13 INFO - 'sessionrestore', 09:12:13 INFO - 'sessionrestore_no_auto_restore', 09:12:13 INFO - 'tabpaint']}, 09:12:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['a11yr', 09:12:13 INFO - 'ts_paint', 09:12:13 INFO - 'tpaint', 09:12:13 INFO - 'sessionrestore', 09:12:13 INFO - 'sessionrestore_no_auto_restore', 09:12:13 INFO - 'tabpaint']}, 09:12:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['tsvgx', 09:12:13 INFO - 'tsvgr_opacity', 09:12:13 INFO - 'tart', 09:12:13 INFO - 'tscrollx', 09:12:13 INFO - 'cart', 09:12:13 INFO - 'tsvg_static']}, 09:12:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:12:13 INFO - 'tsvgr_opacity', 09:12:13 INFO - 'tart', 09:12:13 INFO - 'tscrollx', 09:12:13 INFO - 'cart', 09:12:13 INFO - 'tsvg_static']}, 09:12:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'talos_options': ['--disable-e10s'], 09:12:13 INFO - 'tests': ['tp5o']}, 09:12:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:12:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'talos_options': ['--disable-e10s', 09:12:13 INFO - '--xperf_path', 09:12:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:13 INFO - 'tests': ['tp5n']}, 09:12:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:12:13 INFO - 'talos_options': ['--xperf_path', 09:12:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:12:13 INFO - 'tests': ['tp5n']}}, 09:12:13 INFO - 'talos.zip': {'path': '', 09:12:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:12:13 INFO - Running post-action listener: _resource_record_post_action 09:12:13 INFO - [mozharness: 2016-12-25 17:12:13.747839Z] Finished populate-webroot step (success) 09:12:13 INFO - [mozharness: 2016-12-25 17:12:13.747928Z] Running create-virtualenv step. 09:12:13 INFO - Running pre-action listener: _resource_record_pre_action 09:12:13 INFO - Running main action method: create_virtualenv 09:12:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:12:13 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 09:12:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:12:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:12:13 INFO - Using real prefix '/usr' 09:12:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:12:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:12:16 INFO - Installing pip.................done. 09:12:16 INFO - Return code: 0 09:12:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:12:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:12:16 INFO - Reading from file tmpfile_stdout 09:12:16 INFO - Output received: 09:12:16 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) 09:12:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:12:16 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')]} 09:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:16 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')]} 09:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:16 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:16 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 09:12:16 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 09:12:16 INFO - Using env: {'DISPLAY': ':0', 09:12:16 INFO - 'HOME': '/home/cltbld', 09:12:16 INFO - 'LANG': 'en_US.UTF-8', 09:12:16 INFO - 'LANGUAGE': 'en_US:en', 09:12:16 INFO - 'LOGNAME': 'cltbld', 09:12:16 INFO - 'MAIL': '/var/mail/cltbld', 09:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:16 INFO - 'MOZ_NO_REMOTE': '1', 09:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:16 INFO - 'NO_EM_RESTART': '1', 09:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:16 INFO - 'PWD': '/builds/slave/test', 09:12:16 INFO - 'SHELL': '/bin/bash', 09:12:16 INFO - 'SHLVL': '1', 09:12:16 INFO - 'TERM': 'linux', 09:12:16 INFO - 'TMOUT': '86400', 09:12:16 INFO - 'USER': 'cltbld', 09:12:16 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:16 INFO - '_': '/tools/buildbot/bin/python'} 09:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:16 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 09:12:16 INFO - Cleaning up... 09:12:16 INFO - Return code: 0 09:12:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:12:16 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')]} 09:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:16 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')]} 09:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:16 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:16 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 09:12:16 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 09:12:16 INFO - Using env: {'DISPLAY': ':0', 09:12:16 INFO - 'HOME': '/home/cltbld', 09:12:16 INFO - 'LANG': 'en_US.UTF-8', 09:12:16 INFO - 'LANGUAGE': 'en_US:en', 09:12:16 INFO - 'LOGNAME': 'cltbld', 09:12:16 INFO - 'MAIL': '/var/mail/cltbld', 09:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:16 INFO - 'MOZ_NO_REMOTE': '1', 09:12:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:16 INFO - 'NO_EM_RESTART': '1', 09:12:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:16 INFO - 'PWD': '/builds/slave/test', 09:12:16 INFO - 'SHELL': '/bin/bash', 09:12:16 INFO - 'SHLVL': '1', 09:12:16 INFO - 'TERM': 'linux', 09:12:16 INFO - 'TMOUT': '86400', 09:12:16 INFO - 'USER': 'cltbld', 09:12:16 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:16 INFO - '_': '/tools/buildbot/bin/python'} 09:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:16 INFO - Downloading/unpacking psutil>=3.1.1 09:12:16 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 09:12:16 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 09:12:16 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 09:12:16 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 09:12:16 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 09:12:16 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 09:12:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:12:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:19 INFO - Installing collected packages: psutil 09:12:19 INFO - Running setup.py install for psutil 09:12:19 INFO - building 'psutil._psutil_linux' extension 09:12:19 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 09:12:19 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 09:12:19 INFO - building 'psutil._psutil_posix' extension 09:12:19 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 09:12:19 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 09:12:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:12:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:12:19 INFO - Successfully installed psutil 09:12:19 INFO - Cleaning up... 09:12:19 INFO - Return code: 0 09:12:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:12:19 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')]} 09:12:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:19 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')]} 09:12:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:19 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:19 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 09:12:19 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 09:12:19 INFO - Using env: {'DISPLAY': ':0', 09:12:19 INFO - 'HOME': '/home/cltbld', 09:12:19 INFO - 'LANG': 'en_US.UTF-8', 09:12:19 INFO - 'LANGUAGE': 'en_US:en', 09:12:19 INFO - 'LOGNAME': 'cltbld', 09:12:19 INFO - 'MAIL': '/var/mail/cltbld', 09:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:19 INFO - 'MOZ_NO_REMOTE': '1', 09:12:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:19 INFO - 'NO_EM_RESTART': '1', 09:12:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:19 INFO - 'PWD': '/builds/slave/test', 09:12:19 INFO - 'SHELL': '/bin/bash', 09:12:19 INFO - 'SHLVL': '1', 09:12:19 INFO - 'TERM': 'linux', 09:12:19 INFO - 'TMOUT': '86400', 09:12:19 INFO - 'USER': 'cltbld', 09:12:19 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:19 INFO - '_': '/tools/buildbot/bin/python'} 09:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:12:19 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 09:12:19 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 09:12:19 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 09:12:19 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 09:12:19 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 09:12:19 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 09:12:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:12:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:12:21 INFO - Installing collected packages: mozsystemmonitor 09:12:21 INFO - Running setup.py install for mozsystemmonitor 09:12:22 INFO - Successfully installed mozsystemmonitor 09:12:22 INFO - Cleaning up... 09:12:22 INFO - Return code: 0 09:12:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:12:22 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')]} 09:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 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')]} 09:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:22 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:22 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 09:12:22 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 09:12:22 INFO - Using env: {'DISPLAY': ':0', 09:12:22 INFO - 'HOME': '/home/cltbld', 09:12:22 INFO - 'LANG': 'en_US.UTF-8', 09:12:22 INFO - 'LANGUAGE': 'en_US:en', 09:12:22 INFO - 'LOGNAME': 'cltbld', 09:12:22 INFO - 'MAIL': '/var/mail/cltbld', 09:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:22 INFO - 'MOZ_NO_REMOTE': '1', 09:12:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:22 INFO - 'NO_EM_RESTART': '1', 09:12:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:22 INFO - 'PWD': '/builds/slave/test', 09:12:22 INFO - 'SHELL': '/bin/bash', 09:12:22 INFO - 'SHLVL': '1', 09:12:22 INFO - 'TERM': 'linux', 09:12:22 INFO - 'TMOUT': '86400', 09:12:22 INFO - 'USER': 'cltbld', 09:12:22 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:22 INFO - '_': '/tools/buildbot/bin/python'} 09:12:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:22 INFO - Downloading/unpacking jsonschema==2.5.1 09:12:22 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 09:12:22 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 09:12:22 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 09:12:22 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 09:12:22 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 09:12:22 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 09:12:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:12:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:12:24 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 09:12:24 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 09:12:24 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 09:12:24 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 09:12:24 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 09:12:24 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 09:12:24 INFO - Downloading functools32-3.2.3-2.tar.gz 09:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:12:24 INFO - warning: no files found matching '*.txt' 09:12:24 INFO - no previously-included directories found matching 'build' 09:12:24 INFO - no previously-included directories found matching 'dist' 09:12:24 INFO - no previously-included directories found matching '.git*' 09:12:24 INFO - Installing collected packages: jsonschema, functools32 09:12:24 INFO - Running setup.py install for functools32 09:12:24 INFO - warning: no files found matching '*.txt' 09:12:24 INFO - no previously-included directories found matching 'build' 09:12:24 INFO - no previously-included directories found matching 'dist' 09:12:24 INFO - no previously-included directories found matching '.git*' 09:12:24 INFO - Successfully installed jsonschema functools32 09:12:24 INFO - Cleaning up... 09:12:24 INFO - Return code: 0 09:12:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:12:24 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')]} 09:12:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:24 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')]} 09:12:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:24 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:24 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 09:12:24 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 09:12:24 INFO - Using env: {'DISPLAY': ':0', 09:12:24 INFO - 'HOME': '/home/cltbld', 09:12:24 INFO - 'LANG': 'en_US.UTF-8', 09:12:24 INFO - 'LANGUAGE': 'en_US:en', 09:12:24 INFO - 'LOGNAME': 'cltbld', 09:12:24 INFO - 'MAIL': '/var/mail/cltbld', 09:12:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:24 INFO - 'MOZ_NO_REMOTE': '1', 09:12:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:24 INFO - 'NO_EM_RESTART': '1', 09:12:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:24 INFO - 'PWD': '/builds/slave/test', 09:12:24 INFO - 'SHELL': '/bin/bash', 09:12:24 INFO - 'SHLVL': '1', 09:12:24 INFO - 'TERM': 'linux', 09:12:24 INFO - 'TMOUT': '86400', 09:12:24 INFO - 'USER': 'cltbld', 09:12:24 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:24 INFO - '_': '/tools/buildbot/bin/python'} 09:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:12:25 INFO - Cleaning up... 09:12:25 INFO - Return code: 0 09:12:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:12:25 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')]} 09:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:25 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')]} 09:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:25 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:25 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 09:12:25 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 09:12:25 INFO - Using env: {'DISPLAY': ':0', 09:12:25 INFO - 'HOME': '/home/cltbld', 09:12:25 INFO - 'LANG': 'en_US.UTF-8', 09:12:25 INFO - 'LANGUAGE': 'en_US:en', 09:12:25 INFO - 'LOGNAME': 'cltbld', 09:12:25 INFO - 'MAIL': '/var/mail/cltbld', 09:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:25 INFO - 'MOZ_NO_REMOTE': '1', 09:12:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:25 INFO - 'NO_EM_RESTART': '1', 09:12:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:25 INFO - 'PWD': '/builds/slave/test', 09:12:25 INFO - 'SHELL': '/bin/bash', 09:12:25 INFO - 'SHLVL': '1', 09:12:25 INFO - 'TERM': 'linux', 09:12:25 INFO - 'TMOUT': '86400', 09:12:25 INFO - 'USER': 'cltbld', 09:12:25 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:25 INFO - '_': '/tools/buildbot/bin/python'} 09:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:25 INFO - Downloading/unpacking blobuploader==1.2.4 09:12:25 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 09:12:25 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 09:12:25 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 09:12:25 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 09:12:25 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 09:12:25 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 09:12:27 INFO - Downloading blobuploader-1.2.4.tar.gz 09:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:12:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:12:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 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 09:12:27 INFO - Downloading docopt-0.6.1.tar.gz 09:12:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:12:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:12:28 INFO - Running setup.py install for blobuploader 09:12:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:12:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:12:28 INFO - Running setup.py install for requests 09:12:28 INFO - Running setup.py install for docopt 09:12:28 INFO - Successfully installed blobuploader requests docopt 09:12:28 INFO - Cleaning up... 09:12:28 INFO - Return code: 0 09:12:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12:28 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')]} 09:12:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:28 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')]} 09:12:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:28 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:28 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 09:12:28 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 09:12:28 INFO - Using env: {'DISPLAY': ':0', 09:12:28 INFO - 'HOME': '/home/cltbld', 09:12:28 INFO - 'LANG': 'en_US.UTF-8', 09:12:28 INFO - 'LANGUAGE': 'en_US:en', 09:12:28 INFO - 'LOGNAME': 'cltbld', 09:12:28 INFO - 'MAIL': '/var/mail/cltbld', 09:12:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:28 INFO - 'MOZ_NO_REMOTE': '1', 09:12:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:28 INFO - 'NO_EM_RESTART': '1', 09:12:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:28 INFO - 'PWD': '/builds/slave/test', 09:12:28 INFO - 'SHELL': '/bin/bash', 09:12:28 INFO - 'SHLVL': '1', 09:12:28 INFO - 'TERM': 'linux', 09:12:28 INFO - 'TMOUT': '86400', 09:12:28 INFO - 'USER': 'cltbld', 09:12:28 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:28 INFO - '_': '/tools/buildbot/bin/python'} 09:12:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:28 INFO - Running setup.py (path:/tmp/pip-vqTDel-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:28 INFO - Running setup.py (path:/tmp/pip-03jgQp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:29 INFO - Running setup.py (path:/tmp/pip-rxI7Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:29 INFO - Running setup.py (path:/tmp/pip-3Xrzmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:29 INFO - Running setup.py (path:/tmp/pip-tDW_cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:29 INFO - Running setup.py (path:/tmp/pip-TGNLJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:29 INFO - Running setup.py (path:/tmp/pip-TV3vRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:29 INFO - Running setup.py (path:/tmp/pip-nPxYQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:29 INFO - Running setup.py (path:/tmp/pip-KB9cgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:29 INFO - Running setup.py (path:/tmp/pip-R32fIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:29 INFO - Running setup.py (path:/tmp/pip-IodS5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:29 INFO - Running setup.py (path:/tmp/pip-asxsE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:29 INFO - Running setup.py (path:/tmp/pip-_sf8IY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:29 INFO - Running setup.py (path:/tmp/pip-YcZhNH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:29 INFO - Running setup.py (path:/tmp/pip-wAb6Ii-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:29 INFO - Running setup.py (path:/tmp/pip-NgrfgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:30 INFO - Running setup.py (path:/tmp/pip-l4q7Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:12:30 INFO - Running setup.py install for manifestparser 09:12:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Running setup.py install for mozcrash 09:12:30 INFO - Running setup.py install for mozdebug 09:12:30 INFO - Running setup.py install for mozdevice 09:12:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Running setup.py install for mozfile 09:12:30 INFO - Running setup.py install for mozhttpd 09:12:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Running setup.py install for mozinfo 09:12:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Running setup.py install for mozInstall 09:12:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:12:30 INFO - Running setup.py install for mozleak 09:12:31 INFO - Running setup.py install for mozlog 09:12:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Running setup.py install for moznetwork 09:12:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Running setup.py install for mozprocess 09:12:31 INFO - Running setup.py install for mozprofile 09:12:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Running setup.py install for mozrunner 09:12:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Running setup.py install for mozscreenshot 09:12:31 INFO - Running setup.py install for moztest 09:12:31 INFO - Running setup.py install for mozversion 09:12:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:12:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:12:31 INFO - Cleaning up... 09:12:31 INFO - Return code: 0 09:12:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:31 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:31 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 09:12:31 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 09:12:31 INFO - Using env: {'DISPLAY': ':0', 09:12:31 INFO - 'HOME': '/home/cltbld', 09:12:31 INFO - 'LANG': 'en_US.UTF-8', 09:12:31 INFO - 'LANGUAGE': 'en_US:en', 09:12:31 INFO - 'LOGNAME': 'cltbld', 09:12:31 INFO - 'MAIL': '/var/mail/cltbld', 09:12:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:31 INFO - 'MOZ_NO_REMOTE': '1', 09:12:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:31 INFO - 'NO_EM_RESTART': '1', 09:12:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:31 INFO - 'PWD': '/builds/slave/test', 09:12:31 INFO - 'SHELL': '/bin/bash', 09:12:31 INFO - 'SHLVL': '1', 09:12:31 INFO - 'TERM': 'linux', 09:12:31 INFO - 'TMOUT': '86400', 09:12:31 INFO - 'USER': 'cltbld', 09:12:31 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:31 INFO - '_': '/tools/buildbot/bin/python'} 09:12:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:12:32 INFO - Running setup.py (path:/tmp/pip-Xs2Eer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:12:32 INFO - Running setup.py (path:/tmp/pip-FFrmkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:12:32 INFO - Running setup.py (path:/tmp/pip-E59d7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:12:32 INFO - Running setup.py (path:/tmp/pip-aSOpkk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:12:32 INFO - Running setup.py (path:/tmp/pip-j_LkZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:12:32 INFO - Running setup.py (path:/tmp/pip-2S8mRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:12:32 INFO - Running setup.py (path:/tmp/pip-YKfTvN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:12:32 INFO - Running setup.py (path:/tmp/pip-kfX9zF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:12:32 INFO - Running setup.py (path:/tmp/pip-KXcFQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:12:32 INFO - Running setup.py (path:/tmp/pip-lObmNU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:12:32 INFO - Running setup.py (path:/tmp/pip-zqEuTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:12:32 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)) 09:12:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:12:32 INFO - Running setup.py (path:/tmp/pip-SXY9ER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:12:33 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)) 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:12:33 INFO - Running setup.py (path:/tmp/pip-BMnTsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:12:33 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)) 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:12:33 INFO - Running setup.py (path:/tmp/pip-eO4dFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:12:33 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)) 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:33 INFO - Running setup.py (path:/tmp/pip-JpnphZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:12:33 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)) 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:12:33 INFO - Running setup.py (path:/tmp/pip-MpHZOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:12:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 09:12:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:12:33 INFO - Running setup.py (path:/tmp/pip-LhW_F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:12:33 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)) 09:12:33 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)) 09:12:33 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)) 09:12:33 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)) 09:12:33 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)) 09:12:33 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)) 09:12:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12: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 09:12:34 INFO - Downloading blessings-1.6.tar.gz 09:12:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:12:34 INFO - Installing collected packages: blessings 09:12:34 INFO - Running setup.py install for blessings 09:12:34 INFO - Successfully installed blessings 09:12:34 INFO - Cleaning up... 09:12:34 INFO - Return code: 0 09:12:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:12:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:34 INFO - Reading from file tmpfile_stdout 09:12:34 INFO - Current package versions: 09:12:34 INFO - argparse == 1.2.1 09:12:34 INFO - blessings == 1.6 09:12:34 INFO - blobuploader == 1.2.4 09:12:34 INFO - docopt == 0.6.1 09:12:34 INFO - functools32 == 3.2.3-2 09:12:34 INFO - jsonschema == 2.5.1 09:12:34 INFO - manifestparser == 1.1 09:12:34 INFO - mozInstall == 1.12 09:12:34 INFO - mozcrash == 1.0 09:12:34 INFO - mozdebug == 0.1 09:12:34 INFO - mozdevice == 0.49 09:12:34 INFO - mozfile == 1.2 09:12:34 INFO - mozhttpd == 0.7 09:12:34 INFO - mozinfo == 0.9 09:12:34 INFO - mozleak == 0.1 09:12:34 INFO - mozlog == 3.4 09:12:34 INFO - moznetwork == 0.27 09:12:34 INFO - mozprocess == 0.23 09:12:34 INFO - mozprofile == 0.28 09:12:34 INFO - mozrunner == 6.13 09:12:34 INFO - mozscreenshot == 0.1 09:12:34 INFO - mozsystemmonitor == 0.3 09:12:34 INFO - moztest == 0.7 09:12:34 INFO - mozversion == 1.4 09:12:34 INFO - psutil == 3.1.1 09:12:34 INFO - requests == 1.2.3 09:12:34 INFO - wsgiref == 0.1.2 09:12:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:12: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')]} 09:12:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12: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')]} 09:12:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:34 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:34 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 09:12:34 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 09:12:34 INFO - Using env: {'DISPLAY': ':0', 09:12:34 INFO - 'HOME': '/home/cltbld', 09:12:34 INFO - 'LANG': 'en_US.UTF-8', 09:12:34 INFO - 'LANGUAGE': 'en_US:en', 09:12:34 INFO - 'LOGNAME': 'cltbld', 09:12:34 INFO - 'MAIL': '/var/mail/cltbld', 09:12:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:34 INFO - 'MOZ_NO_REMOTE': '1', 09:12:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:34 INFO - 'NO_EM_RESTART': '1', 09:12:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:34 INFO - 'PWD': '/builds/slave/test', 09:12:34 INFO - 'SHELL': '/bin/bash', 09:12:34 INFO - 'SHLVL': '1', 09:12:34 INFO - 'TERM': 'linux', 09:12:34 INFO - 'TMOUT': '86400', 09:12:34 INFO - 'USER': 'cltbld', 09:12:34 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:34 INFO - '_': '/tools/buildbot/bin/python'} 09:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 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)) 09:12:35 INFO - Cleaning up... 09:12:35 INFO - Return code: 0 09:12:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:12:35 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')]} 09:12:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:35 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')]} 09:12:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:12:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:12:35 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 0x28bc1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2a88a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c2dc70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, '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': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', '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 09:12:35 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 09:12:35 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 09:12:35 INFO - Using env: {'DISPLAY': ':0', 09:12:35 INFO - 'HOME': '/home/cltbld', 09:12:35 INFO - 'LANG': 'en_US.UTF-8', 09:12:35 INFO - 'LANGUAGE': 'en_US:en', 09:12:35 INFO - 'LOGNAME': 'cltbld', 09:12:35 INFO - 'MAIL': '/var/mail/cltbld', 09:12:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:35 INFO - 'MOZ_NO_REMOTE': '1', 09:12:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:35 INFO - 'NO_EM_RESTART': '1', 09:12:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:35 INFO - 'PWD': '/builds/slave/test', 09:12:35 INFO - 'SHELL': '/bin/bash', 09:12:35 INFO - 'SHLVL': '1', 09:12:35 INFO - 'TERM': 'linux', 09:12:35 INFO - 'TMOUT': '86400', 09:12:35 INFO - 'USER': 'cltbld', 09:12:35 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:35 INFO - '_': '/tools/buildbot/bin/python'} 09:12:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:12:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:12:35 INFO - Cleaning up... 09:12:35 INFO - Return code: 0 09:12:35 INFO - Running post-action listener: _resource_record_post_action 09:12:35 INFO - Running post-action listener: _start_resource_monitoring 09:12:35 INFO - Starting resource monitoring. 09:12:35 INFO - [mozharness: 2016-12-25 17:12:35.408945Z] Finished create-virtualenv step (success) 09:12:35 INFO - [mozharness: 2016-12-25 17:12:35.409427Z] Running install step. 09:12:35 INFO - Running pre-action listener: _resource_record_pre_action 09:12:35 INFO - Running main action method: install 09:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:12:35 INFO - Reading from file tmpfile_stdout 09:12:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:12:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:12:35 INFO - Reading from file tmpfile_stdout 09:12:35 INFO - Output received: 09:12:35 INFO - Usage: mozinstall [options] installer 09:12:35 INFO - Options: 09:12:35 INFO - -h, --help show this help message and exit 09:12:35 INFO - -d DEST, --destination=DEST 09:12:35 INFO - Directory to install application into. [default: 09:12:35 INFO - "/builds/slave/test"] 09:12:35 INFO - --app=APP Application being installed. [default: firefox] 09:12:35 INFO - mkdir: /builds/slave/test/build/application 09:12:35 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'] 09:12:35 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 09:12:53 INFO - Reading from file tmpfile_stdout 09:12:53 INFO - Output received: 09:12:53 INFO - /builds/slave/test/build/application/firefox/firefox 09:12:53 INFO - Running post-action listener: _resource_record_post_action 09:12:53 INFO - [mozharness: 2016-12-25 17:12:53.050195Z] Finished install step (success) 09:12:53 INFO - [mozharness: 2016-12-25 17:12:53.050442Z] Running run-tests step. 09:12:53 INFO - Running pre-action listener: _resource_record_pre_action 09:12:53 INFO - Running main action method: run_tests 09:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:12:53 INFO - Python 2.7.3 09:12:53 INFO - Return code: 0 09:12:53 INFO - grabbing minidump binary from tooltool 09:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:12:53 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 0x2c2b3f0>, '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 0x2c2bcf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c2c1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:12:53 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 09:12:53 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 09:12:53 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 09:12:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:12:53 INFO - Return code: 0 09:12:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:12:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:12:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:12:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:12:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:12:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 09:12:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-009 --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 09:12:53 INFO - Using env: {'DISPLAY': ':0', 09:12:53 INFO - 'HOME': '/home/cltbld', 09:12:53 INFO - 'LANG': 'en_US.UTF-8', 09:12:53 INFO - 'LANGUAGE': 'en_US:en', 09:12:53 INFO - 'LOGNAME': 'cltbld', 09:12:53 INFO - 'MAIL': '/var/mail/cltbld', 09:12:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:12:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:12:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:12:53 INFO - 'MOZ_NO_REMOTE': '1', 09:12:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:12:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:12:53 INFO - 'NO_EM_RESTART': '1', 09:12:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:12:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:12:53 INFO - 'PWD': '/builds/slave/test', 09:12:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:12:53 INFO - 'SHELL': '/bin/bash', 09:12:53 INFO - 'SHLVL': '1', 09:12:53 INFO - 'TERM': 'linux', 09:12:53 INFO - 'TMOUT': '86400', 09:12:53 INFO - 'USER': 'cltbld', 09:12:53 INFO - 'XDG_SESSION_COOKIE': '17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061', 09:12:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:12:53 INFO - '_': '/tools/buildbot/bin/python'} 09:12:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-009', '--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 09:12:53 INFO - mozversion application_buildid: 20161225073114 09:12:53 INFO - mozversion application_changeset: 5ea0c495d3b2318287bffe1121e0e33d74427143 09:12:53 INFO - mozversion application_display_name: Nightly 09:12:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:12:53 INFO - mozversion application_name: Firefox 09:12:53 INFO - mozversion application_remotingname: firefox 09:12:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:12:53 INFO - mozversion application_vendor: Mozilla 09:12:53 INFO - mozversion application_version: 53.0a1 09:12:53 INFO - mozversion platform_buildid: 20161225073114 09:12:53 INFO - mozversion platform_changeset: 5ea0c495d3b2318287bffe1121e0e33d74427143 09:12:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:12:53 INFO - mozversion platform_version: 53.0a1 09:12:53 INFO - using testdate: 1482685973 09:12:53 INFO - actual date: 1482685973 09:12:53 INFO - starting webserver on 'localhost:57548' 09:12:53 INFO - SUITE-START | Running 2 tests 09:12:53 INFO - TEST-START | dromaeo_css 09:12:53 INFO - operating with platform_type : linux_ 09:12:53 INFO - Initialising browser for dromaeo_css test... 09:12:53 INFO - TEST-INFO | started process 12477 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiZXiEt/profile http://localhost:57548/getInfo.html) 09:12:53 INFO - PROCESS | 12477 | 1482685973811 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 09:12:57 INFO - PROCESS | 12477 | __metrics Screen width/height:1600/1200 09:12:57 INFO - PROCESS | 12477 | colorDepth:24 09:12:57 INFO - PROCESS | 12477 | Browser inner width/height: 1024/697 09:12:57 INFO - PROCESS | 12477 | __metrics 09:12:58 ERROR - PROCESS | 12477 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:12:59 INFO - TEST-INFO | 12477: exit 0 09:12:59 INFO - Browser initialized. 09:12:59 INFO - Running cycle 1/1 for dromaeo_css test... 09:12:59 INFO - TEST-INFO | started process 12599 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiZXiEt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:13:11 INFO - PROCESS | 12599 | RSS: Main: 154103808 09:13:11 INFO - PROCESS | 12599 | 09:16:11 INFO - PROCESS | 12599 | Cycle 1(1): loaded http://localhost:57548/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57548/tests/dromaeo/cssquery-ext.html) 09:16:12 INFO - PROCESS | 12599 | RSS: Main: 163274752 09:16:12 INFO - PROCESS | 12599 | 09:17:59 INFO - PROCESS | 12599 | ************************* 09:17:59 INFO - PROCESS | 12599 | A coding exception was thrown and uncaught in a Task. 09:17:59 INFO - PROCESS | 12599 | 09:17:59 INFO - PROCESS | 12599 | Full message: ReferenceError: fetch is not defined 09:17:59 INFO - PROCESS | 12599 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:17:59 INFO - PROCESS | 12599 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:17:59 INFO - PROCESS | 12599 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:17:59 INFO - PROCESS | 12599 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:17:59 INFO - PROCESS | 12599 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:17:59 INFO - PROCESS | 12599 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:17:59 INFO - PROCESS | 12599 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:17:59 INFO - PROCESS | 12599 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:17:59 INFO - PROCESS | 12599 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:17:59 INFO - PROCESS | 12599 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:17:59 INFO - PROCESS | 12599 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:17:59 INFO - PROCESS | 12599 | 09:17:59 INFO - PROCESS | 12599 | ************************* 09:19:15 INFO - PROCESS | 12599 | Cycle 1(1): loaded http://localhost:57548/tests/dromaeo/cssquery-ext.html (next: http://localhost:57548/tests/dromaeo/cssquery-jquery.html) 09:19:15 INFO - PROCESS | 12599 | RSS: Main: 160092160 09:19:15 INFO - PROCESS | 12599 | 09:22:17 INFO - PROCESS | 12599 | Cycle 1(1): loaded http://localhost:57548/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57548/tests/dromaeo/cssquery-mootools.html) 09:22:18 INFO - PROCESS | 12599 | RSS: Main: 164347904 09:22:18 INFO - PROCESS | 12599 | 09:25:20 INFO - PROCESS | 12599 | Cycle 1(1): loaded http://localhost:57548/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57548/tests/dromaeo/cssquery-prototype.html) 09:25:20 INFO - PROCESS | 12599 | RSS: Main: 159641600 09:25:20 INFO - PROCESS | 12599 | 09:28:23 INFO - PROCESS | 12599 | Cycle 1(1): loaded http://localhost:57548/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57548/tests/dromaeo/cssquery-yui.html) 09:28:24 INFO - PROCESS | 12599 | RSS: Main: 164130816 09:28:24 INFO - PROCESS | 12599 | 09:31:26 INFO - PROCESS | 12599 | Cycle 1(1): loaded http://localhost:57548/tests/dromaeo/cssquery-yui.html (next: http://localhost:57548/tests/dromaeo/cssquery-dojo.html) 09:31:27 INFO - PROCESS | 12599 | RSS: Main: 163033088 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | __start_tp_report 09:31:27 INFO - PROCESS | 12599 | _x_x_mozilla_page_load 09:31:27 INFO - PROCESS | 12599 | _x_x_mozilla_page_load_details 09:31:27 INFO - PROCESS | 12599 | |i|pagename|runs| 09:31:27 INFO - PROCESS | 12599 | |0;dojo.html;3146.3549972255682;3175.2007410452893;3179.841007949602;3205.727513161381;3208.9197770055753;5296.09966305728;5390.245365648812;5410.891782164359;5414.485623865731;5454.509094181532;5341.4658534146665;5372.677639341642;5438.864028399292;5458.426865179159;5501.0648189807725;4820.325154478376;4852.078105160008;4886.755662216893;4971.229459433794;4973.452920178777;4875.975620121894;4991.301217829507;4999.525045120708;5006.424261209971;5006.774695138727;4809.519048095198;4931.112399768041;4938.283948827418;4991.550760431579;5014.598832093424;4834.323194752737;4856.15017371962;4870.65905386623;4904.4605093439695;4994.87512812179;4867.415910090776;4872.683275587111;4893.941425669579;4920.827771027997;4923.532264434909;5410.242566040757;5421.457854214586;5422.15956526739;5424.213489044106;5453.563714902838;5633.0987042073075;5686.715664216814;5701.2018317435595;5713.085906254979;5732.656040637575;5804.187413762077;5806.941930580682;5809.6804675742815;5825.7378417971295;5877.735501902424;5397.325334333208;5411.404745477994;5414.3232095988005;5415.079436495806;5415.16065009928;5800.59395842287;5830.154627578977;5837.124431335334;5844.035734103911;5937.050071988461;5612.354579223442;5641.746121424546;5643.7742490299925;5690.231818704422;5699.857503562361;5701;5714.857128571736;5765.769369225268;5771.797987070517;5792.681402522818;5649.4633009864;5671.602987790898;5675.262215911905;5739.339975902742;5753.395893431213;5714.571407144397;5731.914021289684;5748.798792042258;5767.163761254678;5784.566157538202;4348.326009468537;4350.521442641307;4366.585174408489;4379.846705365297;4393.538678438782;3887.9805600971813;3910.0420397002918;3914.041059940333;3940.7438516496336;3944.467496887941;5327.653702509325;5353.705546194997;5409.675419474844;5420.322459692538;5506.476884695948;5785.739641716133;5839;5858.238429004202;5865.677387743715;5919.585629005928;5398.271233383522;5437.13005919051;5468.152436372368;5490.588205884575;5513.283273174462;4726.88182795426;4755.690880092783;4779.617630589544;4848.175810112219;4904;5975.910361344583;6036.788712395045;6053.4854537363935;6081.543884208702;6082.057281121433;5809.65142091459;5817.301923769175;5823.592348535732;5823.621464604787;5834;5903.380145084704;5929.110633405024;6084.300305464752;6099.573029887865;6100.267967843887;5633.2395126657375;5679.659220446786;5688.089905615082;5729.828105156852;5787.421257874179;5804.158397032491;5844.12338149281;5848.824535263949;5860.472557469848;5860.677662728591;5552.111662134039;5668.093105103164;5705.172749951317;5737.827865164052;5740.655560666373;5578.721063946868;5669.149627555735;5672.092465205547;5674.886502270014;5775.624584402;5675.460831221028;5702.515286200553;5720.742566584597;5723.198752174616;5725.856853578527;5600.747966341443;5642.040853055072;5734.05388110957;5778.508826749773;5794.159846822272;5380.569554435558;5400.648957817729;5483.533899618577;5536.806211782729;5584.86037849057 09:31:27 INFO - PROCESS | 12599 | |1;ext.html;549.7059073395734;562.9296337957755;566.9773209071637;568.5281216590231;574.7815829984605;40530.18939621206;42826.35760463595;42903.141937161236;46341.30488042674;46471.99999999991;44354.33468497975;44623.330650040276;44671.10657786842;44765.328520072224;44785.55214447855;8643.222110010081;8801.779955501099;8803.691870784523;8824.249938755214;10038.795344558626;10154.695359139238;10250.282480226388;10264.89735102647;10274.845877311845;10277.845832312483;10366.948165259164;10395.012473814975;10396.792064158712;10397.220208484394;10415.427151506665;10301.918298578654;10359.896401035969;10360;10360.222983276284;10368.896311036944;10211.285210035301;10271.486425678686;10285.331453455576;10294.948525257325;10297.12474439666;37466;37638.43542346867;38030.23939521194;38044.429333560016;38134;3916.7649941003633;4057.513098428178;4060.4315395844606;4065.166640838621;4075.7758323292205;28761.424771504662;29380.559291610643;29381.559276610868;29499;29908;3973.821178046966;4046.190761847613;4234.343676730112;4260.169266992938;4274.252005898955;313294;313351;314067;314952;315172.99999999773;50998.49001509937;51015.489845101816;51022.48977510177;51316.743416283425;51470.48529514657;52558.73720631372;52962.20556691653;53068.75449964071;53266.73366633142;53811.73094134504;15006.174660393564;15371;15456.459023934332;15493.302801373966;15653.608659783597;11870.406479675981;12114.69713257158;12123.45444454984;12187;12403.193792403465;16670.916645416695;16816.915915420588;16853.157342132843;16882.324707011827;16884.577885552713;6675.3324667533825;6680;6786.864262714718;6806.285340039224;6921.619310937846;893.5755516129786;902.873597696323;906.7823722306599;907.6823111910778;939.5396255834728;29407.713839827422;29465.02139814519;29584.189501997276;30212.84893575518;30987.69012309893;1075.2956813287153;1077.3159044006954;1092.7377429416883;1107.6953837694698;1148.9831499123336;15699;16196.838031619533;16291.918540407221;16378.918105409635;16426.83573164277;480764;482871;488258;490886;730365;52152;52430;52526.737366312926;52796.20805687918;52832.73583632057;1351.8986076044337;1361.5030513862368;1385.6328073060647;1386.3969173409398;1404.7331007108617;27555.31111722223;27601.58597620997;28198.71801281961;28446.57330140008;28999.56500652534;44468.332975004756;44758.77620611876;44838.86127971011;45299;45346;343565;345222;345955;346595;346721;23496.412589685395;23739.16912908109;24974;25042.3739406509;25209.487430754212;40633.79683101566;40689.982750431474;41217;41444;42199.367009494264;34461;34710.65289347074;34749;34934.82532587321;34935.12662183466;33465;33744.49383259204;33806.49290260697;33930.151746206546;34098.829505852314;34336.141596460286;34798;35269.82365088158;35499.64500354963;35670.82164589161;28255.29361765972;31263.218419538785;31331.84334078406;31404.685953141092;31494.370112598077;24346.51306973886;24384.390390240387;24450.877745611157;24455.510889781748;24616.876915615307 09:31:27 INFO - PROCESS | 12599 | |2;jquery.html;390.8807813616849;394.08965290179685;402.7060246020405;404.6863680647498;422.953475117737;16492.917535412336;16498.505044848673;17655.20551575179;18910.338138165167;19116.904415477922;12093.758124837497;12138.271703697785;12335.259884406942;12547.121701480877;12701.428435720392;4965.3296804931415;5065.898682026357;5117.769700363474;5124.461931497195;5138.58891288696;5016.27264046713;5059.595232381401;5378.2470454136455;5701.828945131632;5742.023855944479;5691.4877661010505;5692.943070569303;5701.885962280752;5707.800226992056;5727.226824378718;5502.944970550283;5691;5692.17463467799;5709.400512946122;5739.827805165852;5150.0472412603785;5150.27896094547;5157.355330583677;5173.5602473789395;5214.930939157502;13660.385282662304;13705.725885482334;13808.17150970935;13858.376373063236;15946.043237405676;2951.070412819955;2995.6255468066493;3036.0852734419727;3049.1462390530687;3073.3545955349405;6200.658963756991;10136.746581335452;10205.18358531323;10307.608472856144;10770.40762758048;1868.1033104110104;1953.8339241164517;1968.3602829080605;1972.6911446656704;1987;69588;71205.64397178033;72641;72803;73427;9085;9419.199368053793;9548.140667339972;9636.277279204087;10111.848322275171;13046.608601741962;13162.934185329012;13165.144265622705;13180.934095329461;13208.009399295091;6936.271691472423;7125.786226413215;7189.604571748604;7375.963120184365;7420.146683131509;6957.547759395724;6959.234484206733;7135.89296160558;7142.642867856586;7196.136463624399;3975.7813320267664;4012.25773231953;4109.97945010279;4170.186813571325;4228.534861165269;1960.3334866145578;1962.4112766170092;1995.2418081129367;2013.7180794688754;2051.661475856497;866.7703058689452;897.1387468030633;902.4449963272672;906.6146887572755;939.3940908114228;17684.46946591604;17895.552611184823;18008.36970706019;18351.449456516326;18608.255669773058;886.5833058462513;887.32563251929;896.2605850173633;900.1853322742928;907.3059109781022;10343;10343.797009334538;10577.735556611146;10582.417967011734;10616.469176541143;194759;198326;430707;441923;442625;73309;73552;73989.63005185047;74243;78368;979.1383582447543;1016.1261315268863;1043.436544266103;1054.9103326217205;1057.038095333243;16033.438829640907;17127;17182.570435738708;17262.482125536255;17515.21181546808;37368;37959;38647.42028869625;39064.41403378895;39281.41077883891;51475;52003.21995170151;52333;53376.199357008896;54486;8263.132371100948;8268.545230012407;8269.95865020695;8451.746447606582;8558.614862331086;9195.908040919772;11911.285322880654;12498.750024999266;12499.687507812378;12682.556110536456;10295.94852025735;10708.678739637824;10722.678319650424;10744.785104297714;11181.888181118411;10488.68533943983;10611.840822387825;10629.521671524955;10679;10892.455377231265;10512;10637.042666160083;10705.250632455653;10709.250352475252;11353.772924541297;8157;8249.216324449168;8259.256666899768;8610.569471526273;8681.479111253093;9150.588223529823;10464.162866970471;10508.579656813949;10521.053105220566;10870.184736144663 09:31:27 INFO - PROCESS | 12599 | |3;mootools.html;1367.4393498665543;1373.0320124312361;1373.9313034348281;1377.4696741754422;1379.4551152294837;7605.353544948686;7848.529088254709;8115.310198633116;8593.441426307285;8630.870536941942;8038.8794168087525;8275.420720549564;8309.584520773968;8400.3279737621;8532.53071081092;2237.6196046672103;2261.208576998046;2293.6100862853355;2302.9309120726407;2303.5968705476557;2059.5057186275335;2129.361191642509;2238.1942500699734;2292.037344315383;2298.2990187992655;2199.83501237407;2286.1198438601177;2290.450291929933;2298.689676893615;2300.3214051854743;2282.2012295696454;2293.598620241451;2307.7807608277353;2316.247219653619;2320.918767843126;2362.858228506278;2371.620540713495;2371.893264803071;2393.6170212765874;2407.0612461140167;8339.416240863142;8446.282066024396;8454.408191426603;8518.27594654455;8541.786455338604;5091.516305950929;5233.790648374061;5236.581073514109;5246;5274.498922602346;3237.498187780874;3378.7803792753393;3424.0583839444103;3424.3151369725906;3453.6028356739043;1316.6181807275877;1362;1388.7569675306781;1398.7202559488144;1410.1891412437787;10528.526216320284;10923.563057477691;10952.780944381067;11116.555337786398;11499.19505634606;4434.5343738907595;4516.88707782308;4709.599684026827;4723.197056500403;4730.479647238801;9722.95138524317;9809.411435313761;9817.656382026595;9825.164860986895;9856.112949834405;5304.071787437183;5305.204219367049;5310.097283461821;5315.840524784263;5317.122674758699;4662.254039353687;4723.197056500403;4797.352357431771;4838.612910967114;4891.730954797449;3679.889603311905;3748.306563285801;3766.114962983697;3791.696664266852;3818.3508803503473;1185.9762804743857;1194.9940250298694;1200.9399530023463;1213.8968187704145;1218.61004478566;934.8924873639619;952.2382094324652;957.1051067252141;959.4579062829479;973.3770386952356;5650.7739690412745;5722.942770572325;5783.421657834267;5986.004804320898;6010.068439391901;922.262190247799;944.40502483435;960.9087136722001;972.6838777397375;1011.4285428732743;3834.9041273968373;4096.344584866407;4102.2000709861295;4141.171765646883;4151.27352713274;9856.704298871045;10166.898331016742;10256.58973641046;10276.434796086287;10287.948560257151;9305.162535371848;9801.11789938909;10086.49567521621;10229.43738094412;10237.43694096817;7803.180666030099;8002.319802816709;8010.319122874621;8015.198480151938;8022.759317220492;5382.416578994339;5387.568994480511;5546.195801608825;5562.082256427644;5601.5238704710555;8924.241439477717;9261.76845578839;9296.07039296065;9412.15290623847;9432.43405395651;9318.72043838686;9457.810843782949;9476.47879366614;9488.95255523718;9727.12455878946;7032.12098487689;7218;7226.168990566152;7537.660805263667;7611.619419029137;8179.263866252066;8194.139615340304;8229.094799571923;8234.629441675019;8271.255586997198;7174.641267936687;7246;7278.235785242473;7534.510256833289;7568.583727894798;7192.496525243183;7309.853802924018;7318.96340518294;7321;7388.556686598821;6395.264544577433;6536.869262614626;6555.147830781968;6558.409743123142;6617.7683781067435;4929.186684197068;4970.403551573834;4981.875453113556;5054.140796064751;5136.512031357015;5014.4484106747495;5095.949040509696;5170;5186.066508028591;5205.271262023395 09:31:27 INFO - PROCESS | 12599 | |4;prototype.html;364.62261559286145;387.94568760373534;393.6260552475147;396.2906397548389;397.2710077008689;5188.169892817141;5325.840224793253;5367.684917195288;5404.891902161954;5434.483724046209;5137.434882162968;5220.817271395502;5253.6322457428;5276.630635855482;5283.8150664726745;4230.7038507304505;4249.723767955088;4273.551277115905;4281.373078230268;4284.957150428487;4114.732542384735;4211.157768446307;4255.787210639471;4310.267254566716;4322.610965013148;4224.471941007374;4225.514065882417;4240.194363071007;4247.129338485618;4247.447831781879;4248.299030659938;4257.531671516131;4270.658347332222;4286.807093680792;4287.292596721537;3900.8350365547394;3902.2195560887653;3904.414337849317;3907.081835768592;3920.6863450924143;5046.873828154289;5123.666961647481;5123.871903202413;5133.152065256505;5133.563647090002;4835.371401717789;4856.077345304416;4874.951250487521;4882.902341953141;4895.510448955076;5435.592330575183;5527.198556209327;5552.972235138798;5554.50009499143;5574.581906356999;4193.643540299078;4202.642775364097;4214.072903961123;4217.873463796091;4220.430241917333;5765.789184271308;5796.449337312949;5817.709114544298;5846.327672317653;5861.2087368205175;4347.434833471629;4351.9564804352185;4354;4364.214441400578;4364.682841040832;3962.980185099114;4165.4795999459975;4185.346788018715;4199.0342221289275;4200.600942910419;4736.289556566542;4756.334113224151;4770.0936822003705;4773.0453909218295;4791.305260737243;5657.462541058593;5673.943260567424;5685.204071430003;5701.458361455655;5709.457601527848;1385.4942945824903;1401.7967394727707;1405.8031875537433;1406.542873566095;1422.5732280315865;953.5327689432129;967.8403063494585;976.9804603907882;987.9527700637348;990.3067852503276;5178.97410512945;5182.378114626269;5208.687478751287;5233.947660523422;5269.815556455505;5539.086050801653;5586.888262234733;5591.636543624732;5626.887462250732;5630.155476678531;5088.414832294259;5095.15929871567;5179.715115668599;5214.661047031931;5226.125630902988;2977.8064425812254;2981.807277089138;2998.3553535989868;3005.6693763685976;3005.8497075146156;5727.680289482766;5770.336411312669;5843;5873.794417195378;5874.236349274567;5414.512693857571;5742.7415766290615;5754.63353937951;5757.971210144006;5770.711464426762;5784.074548072373;5846.684495988368;5855.414458554195;5882.676452795137;5894.646321220741;5292.311999440049;5502.312210973629;5532.446755324436;5599.972000139973;5635.239242702182;5467.371252305877;5564.276644036249;5571.164325351229;5574.216250799705;5610.747516361856;5783.392743761844;5791.681457519709;5813.912791308218;5843.2695913010875;5853.28316519022;3925.371940489508;4041.7170798043853;4141.316682747313;4173.8956526087095;4184.81168347419;5724.540242238202;5753.827385178452;5764.13537969308;5768.884622307446;5782.595218334676;5529.944700552943;5594.496495315441;5601.571599242167;5609.4390560943575;5613.12996485529;5544.556435485071;5601.551875850004;5605.299337582802;5613.719314034363;5622.409646987007;5573.7213139343685;5595.132754423071;5605.5515558756015;5625.437456254342;5638.85902852432;5466.262054622575;5641.323041234914;5657.773689052557;5671.773129074791;5698.287714035746;5589.189567512606;5593.664380137205;5608.298962629671;5612.270404847343;5623.3814280428305 09:31:27 INFO - PROCESS | 12599 | |5;yui.html;733.5232099135561;761.6458346868704;790.3361176611645;831.3432388413154;853.71827296992;14546.418143274283;14973.92513037436;15001.924990375033;15062.397504099836;15969.121698306588;13266.270355130464;14155.504557340495;14311.713765724679;14326.068805527633;14374.49689260876;806.1978331560106;825.0882774534143;826.1408135539032;827.0819390476566;828.3456069704926;813.9796505087361;824.2952275804191;824.4146655874306;827.0819390476566;827.1191181391798;838.7567605394428;839.4291881520563;839.7396806989822;847.2459511035183;853.4239388412827;826.6817275349034;828.9005319361655;831.3806214370272;840.1220724343045;840.8024114333127;606.2997238189897;606.5087279303779;614.9354317796611;618.8421952402109;619.2414292491707;12930.48278068876;12936.870631293661;13551.796723049161;13676.111052781538;13697.041207115506;12372.56696015644;12489.562865299762;12972.675683107904;13094;13119.081664283503;11249.381284029376;11382.544698212061;11452.427378631119;11563.364014979174;11656.533738650529;3762.3039737648623;3768.9811550942068;3776.150366167618;3783.848646054182;3800.4869342638667;71385;72028.63985680038;72089.27910720931;72368;73730;9659.565319560637;9774.657886973922;9785.412875227508;9801.460919649488;9967.651132210338;19591.608167836563;19612.509687257647;19628.901855490632;19655.312064077687;19666.803331966497;8060.113387527367;8195.877061844078;8305.46014509055;8350.498970061693;8497.277731392776;4382.320740285261;4420.624246938982;4450.46594408673;4503.842365517191;4656.441227052707;3531.346700860349;3605.387084195693;3612.548431446069;3646.835892384849;3659.908502287464;590.2385922160365;598.07000114821;599.340725202274;608.7321578505532;618.5639124417365;703.4442790195791;714.3713532091726;715.1096884378982;723.8624661314334;730.8392153726172;8732.214100730966;9045;9171.174594286545;9176.08239176077;9334.20659243972;724.7028718225503;725.5646612032843;728.2462651156134;734.0494060192065;748.7500062312715;4221.2401767681495;4541.2733962565835;4579.381783459257;4595.977020114945;4667.789949452283;71349.28650713427;75216.62391688007;76225.61887190529;77695.22304776993;78507.60746196233;66528.33471665221;66823.66588167028;67213.66393168003;67695;67841;59176.408235917086;59720;59812.40187598155;60672.696636516535;60699.393006069375;8587.65649374018;8792.824143517222;8792.977615148424;8991.685291014783;9083.137101975302;41863.37204941989;41896.371554427315;42200;43282.56717432785;43436;51217.48782512127;51696.741516292175;52932.73533632307;53108.20337695015;53645.463545364044;10422.687319380431;10446.63436779709;10816.4050977197;10901.127909767041;11929.224600400947;12587.87412125867;13502.594922152352;13800.792988104986;14388.784168237275;14773.199754489273;12842.745958890291;13021.283829389475;13078.28069456189;13182.736345273233;13841.930790345983;13080;13695.794563081761;13857.099288546213;14490.275486225857;14877.925610371878;12950.417231224807;12981.870181298065;13769.31153442344;14201.360938757987;14801.851981480479;10626.787464250518;10737.89262107369;10889;10956.945215273872;10970.341779493256;9996.550155243178;10294.220560448786;10309.22680798928;10493;10554.683359499228 09:31:27 INFO - PROCESS | 12599 | __end_tp_report 09:31:27 INFO - PROCESS | 12599 | __start_cc_report 09:31:27 INFO - PROCESS | 12599 | _x_x_mozilla_cycle_collect,309 09:31:27 INFO - PROCESS | 12599 | __end_cc_report 09:31:27 INFO - PROCESS | 12599 | __startTimestamp1482687087094__endTimestamp 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | ------- Summary: start ------- 09:31:27 INFO - PROCESS | 12599 | Number of tests: 6 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | [#0] dojo.html Cycles:165 Average:5368.43 Median:5600.75 stddev:615.62 (11.0%) stddev-sans-first:592.31 09:31:27 INFO - PROCESS | 12599 | Values: 3146.4 3175.2 3179.8 3205.7 3208.9 5296.1 5390.2 5410.9 5414.5 5454.5 5341.5 5372.7 5438.9 5458.4 5501.1 4820.3 4852.1 4886.8 4971.2 4973.5 4876.0 4991.3 4999.5 5006.4 5006.8 4809.5 4931.1 4938.3 4991.6 5014.6 4834.3 4856.2 4870.7 4904.5 4994.9 4867.4 4872.7 4893.9 4920.8 4923.5 5410.2 5421.5 5422.2 5424.2 5453.6 5633.1 5686.7 5701.2 5713.1 5732.7 5804.2 5806.9 5809.7 5825.7 5877.7 5397.3 5411.4 5414.3 5415.1 5415.2 5800.6 5830.2 5837.1 5844.0 5937.1 5612.4 5641.7 5643.8 5690.2 5699.9 5701.0 5714.9 5765.8 5771.8 5792.7 5649.5 5671.6 5675.3 5739.3 5753.4 5714.6 5731.9 5748.8 5767.2 5784.6 4348.3 4350.5 4366.6 4379.8 4393.5 3888.0 3910.0 3914.0 3940.7 3944.5 5327.7 5353.7 5409.7 5420.3 5506.5 5785.7 5839.0 5858.2 5865.7 5919.6 5398.3 5437.1 5468.2 5490.6 5513.3 4726.9 4755.7 4779.6 4848.2 4904.0 5975.9 6036.8 6053.5 6081.5 6082.1 5809.7 5817.3 5823.6 5823.6 5834.0 5903.4 5929.1 6084.3 6099.6 6100.3 5633.2 5679.7 5688.1 5729.8 5787.4 5804.2 5844.1 5848.8 5860.5 5860.7 5552.1 5668.1 5705.2 5737.8 5740.7 5578.7 5669.1 5672.1 5674.9 5775.6 5675.5 5702.5 5720.7 5723.2 5725.9 5600.7 5642.0 5734.1 5778.5 5794.2 5380.6 5400.6 5483.5 5536.8 5584.9 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | [#1] ext.html Cycles:180 Average:54565.62 Median:34217.49 stddev:110035.77 (321.6%) stddev-sans-first:110269.71 09:31:27 INFO - PROCESS | 12599 | Values: 549.7 562.9 567.0 568.5 574.8 40530.2 42826.4 42903.1 46341.3 46472.0 44354.3 44623.3 44671.1 44765.3 44785.6 8643.2 8801.8 8803.7 8824.2 10038.8 10154.7 10250.3 10264.9 10274.8 10277.8 10366.9 10395.0 10396.8 10397.2 10415.4 10301.9 10359.9 10360.0 10360.2 10368.9 10211.3 10271.5 10285.3 10294.9 10297.1 37466.0 37638.4 38030.2 38044.4 38134.0 3916.8 4057.5 4060.4 4065.2 4075.8 28761.4 29380.6 29381.6 29499.0 29908.0 3973.8 4046.2 4234.3 4260.2 4274.3 313294.0 313351.0 314067.0 314952.0 315173.0 50998.5 51015.5 51022.5 51316.7 51470.5 52558.7 52962.2 53068.8 53266.7 53811.7 15006.2 15371.0 15456.5 15493.3 15653.6 11870.4 12114.7 12123.5 12187.0 12403.2 16670.9 16816.9 16853.2 16882.3 16884.6 6675.3 6680.0 6786.9 6806.3 6921.6 893.6 902.9 906.8 907.7 939.5 29407.7 29465.0 29584.2 30212.8 30987.7 1075.3 1077.3 1092.7 1107.7 1149.0 15699.0 16196.8 16291.9 16378.9 16426.8 480764.0 482871.0 488258.0 490886.0 730365.0 52152.0 52430.0 52526.7 52796.2 52832.7 1351.9 1361.5 1385.6 1386.4 1404.7 27555.3 27601.6 28198.7 28446.6 28999.6 44468.3 44758.8 44838.9 45299.0 45346.0 343565.0 345222.0 345955.0 346595.0 346721.0 23496.4 23739.2 24974.0 25042.4 25209.5 40633.8 40690.0 41217.0 41444.0 42199.4 34461.0 34710.7 34749.0 34934.8 34935.1 33465.0 33744.5 33806.5 33930.2 34098.8 34336.1 34798.0 35269.8 35499.6 35670.8 28255.3 31263.2 31331.8 31404.7 31494.4 24346.5 24384.4 24450.9 24455.5 24616.9 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | [#2] jquery.html Cycles:180 Average:23006.30 Median:2184.94 stddev:60152.92 (2753.1%) stddev-sans-first:60297.70 09:31:27 INFO - PROCESS | 12599 | Values: 390.9 394.1 402.7 404.7 423.0 16492.9 16498.5 17655.2 18910.3 19116.9 12093.8 12138.3 12335.3 12547.1 12701.4 4965.3 5065.9 5117.8 5124.5 5138.6 5016.3 5059.6 5378.2 5701.8 5742.0 5691.5 5692.9 5701.9 5707.8 5727.2 5502.9 5691.0 5692.2 5709.4 5739.8 5150.0 5150.3 5157.4 5173.6 5214.9 13660.4 13705.7 13808.2 13858.4 15946.0 2951.1 2995.6 3036.1 3049.1 3073.4 6200.7 10136.7 10205.2 10307.6 10770.4 1868.1 1953.8 1968.4 1972.7 1987.0 69588.0 71205.6 72641.0 72803.0 73427.0 9085.0 9419.2 9548.1 9636.3 10111.8 13046.6 13162.9 13165.1 13180.9 13208.0 6936.3 7125.8 7189.6 7376.0 7420.1 6957.5 6959.2 7135.9 7142.6 7196.1 3975.8 4012.3 4110.0 4170.2 4228.5 1960.3 1962.4 1995.2 2013.7 2051.7 866.8 897.1 902.4 906.6 939.4 17684.5 17895.6 18008.4 18351.4 18608.3 886.6 887.3 896.3 900.2 907.3 10343.0 10343.8 10577.7 10582.4 10616.5 194759.0 198326.0 430707.0 441923.0 442625.0 73309.0 73552.0 73989.6 74243.0 78368.0 979.1 1016.1 1043.4 1054.9 1057.0 16033.4 17127.0 17182.6 17262.5 17515.2 37368.0 37959.0 38647.4 39064.4 39281.4 51475.0 52003.2 52333.0 53376.2 54486.0 8263.1 8268.5 8270.0 8451.7 8558.6 9195.9 11911.3 12498.8 12499.7 12682.6 10295.9 10708.7 10722.7 10744.8 11181.9 10488.7 10611.8 10629.5 10679.0 10892.5 10512.0 10637.0 10705.3 10709.3 11353.8 8157.0 8249.2 8259.3 8610.6 8681.5 9150.6 10464.2 10508.6 10521.1 10870.2 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | [#3] mootools.html Cycles:180 Average:5534.69 Median:5241.29 stddev:3018.47 (57.6%) stddev-sans-first:3010.69 09:31:27 INFO - PROCESS | 12599 | Values: 1367.4 1373.0 1373.9 1377.5 1379.5 7605.4 7848.5 8115.3 8593.4 8630.9 8038.9 8275.4 8309.6 8400.3 8532.5 2237.6 2261.2 2293.6 2302.9 2303.6 2059.5 2129.4 2238.2 2292.0 2298.3 2199.8 2286.1 2290.5 2298.7 2300.3 2282.2 2293.6 2307.8 2316.2 2320.9 2362.9 2371.6 2371.9 2393.6 2407.1 8339.4 8446.3 8454.4 8518.3 8541.8 5091.5 5233.8 5236.6 5246.0 5274.5 3237.5 3378.8 3424.1 3424.3 3453.6 1316.6 1362.0 1388.8 1398.7 1410.2 10528.5 10923.6 10952.8 11116.6 11499.2 4434.5 4516.9 4709.6 4723.2 4730.5 9723.0 9809.4 9817.7 9825.2 9856.1 5304.1 5305.2 5310.1 5315.8 5317.1 4662.3 4723.2 4797.4 4838.6 4891.7 3679.9 3748.3 3766.1 3791.7 3818.4 1186.0 1195.0 1200.9 1213.9 1218.6 934.9 952.2 957.1 959.5 973.4 5650.8 5722.9 5783.4 5986.0 6010.1 922.3 944.4 960.9 972.7 1011.4 3834.9 4096.3 4102.2 4141.2 4151.3 9856.7 10166.9 10256.6 10276.4 10287.9 9305.2 9801.1 10086.5 10229.4 10237.4 7803.2 8002.3 8010.3 8015.2 8022.8 5382.4 5387.6 5546.2 5562.1 5601.5 8924.2 9261.8 9296.1 9412.2 9432.4 9318.7 9457.8 9476.5 9489.0 9727.1 7032.1 7218.0 7226.2 7537.7 7611.6 8179.3 8194.1 8229.1 8234.6 8271.3 7174.6 7246.0 7278.2 7534.5 7568.6 7192.5 7309.9 7319.0 7321.0 7388.6 6395.3 6536.9 6555.1 6558.4 6617.8 4929.2 4970.4 4981.9 5054.1 5136.5 5014.4 5095.9 5170.0 5186.1 5205.3 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | [#4] prototype.html Cycles:180 Average:4717.91 Median:5288.06 stddev:1350.53 (25.5%) stddev-sans-first:1314.20 09:31:27 INFO - PROCESS | 12599 | Values: 364.6 387.9 393.6 396.3 397.3 5188.2 5325.8 5367.7 5404.9 5434.5 5137.4 5220.8 5253.6 5276.6 5283.8 4230.7 4249.7 4273.6 4281.4 4285.0 4114.7 4211.2 4255.8 4310.3 4322.6 4224.5 4225.5 4240.2 4247.1 4247.4 4248.3 4257.5 4270.7 4286.8 4287.3 3900.8 3902.2 3904.4 3907.1 3920.7 5046.9 5123.7 5123.9 5133.2 5133.6 4835.4 4856.1 4875.0 4882.9 4895.5 5435.6 5527.2 5553.0 5554.5 5574.6 4193.6 4202.6 4214.1 4217.9 4220.4 5765.8 5796.4 5817.7 5846.3 5861.2 4347.4 4352.0 4354.0 4364.2 4364.7 3963.0 4165.5 4185.3 4199.0 4200.6 4736.3 4756.3 4770.1 4773.0 4791.3 5657.5 5673.9 5685.2 5701.5 5709.5 1385.5 1401.8 1405.8 1406.5 1422.6 953.5 967.8 977.0 988.0 990.3 5179.0 5182.4 5208.7 5233.9 5269.8 5539.1 5586.9 5591.6 5626.9 5630.2 5088.4 5095.2 5179.7 5214.7 5226.1 2977.8 2981.8 2998.4 3005.7 3005.8 5727.7 5770.3 5843.0 5873.8 5874.2 5414.5 5742.7 5754.6 5758.0 5770.7 5784.1 5846.7 5855.4 5882.7 5894.6 5292.3 5502.3 5532.4 5600.0 5635.2 5467.4 5564.3 5571.2 5574.2 5610.7 5783.4 5791.7 5813.9 5843.3 5853.3 3925.4 4041.7 4141.3 4173.9 4184.8 5724.5 5753.8 5764.1 5768.9 5782.6 5529.9 5594.5 5601.6 5609.4 5613.1 5544.6 5601.6 5605.3 5613.7 5622.4 5573.7 5595.1 5605.6 5625.4 5638.9 5466.3 5641.3 5657.8 5671.8 5698.3 5589.2 5593.7 5608.3 5612.3 5623.4 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - PROCESS | 12599 | [#5] yui.html Cycles:180 Average:16746.69 Median:52314.74 stddev:21374.75 (40.9%) stddev-sans-first:21400.89 09:31:27 INFO - PROCESS | 12599 | Values: 733.5 761.6 790.3 831.3 853.7 14546.4 14973.9 15001.9 15062.4 15969.1 13266.3 14155.5 14311.7 14326.1 14374.5 806.2 825.1 826.1 827.1 828.3 814.0 824.3 824.4 827.1 827.1 838.8 839.4 839.7 847.2 853.4 826.7 828.9 831.4 840.1 840.8 606.3 606.5 614.9 618.8 619.2 12930.5 12936.9 13551.8 13676.1 13697.0 12372.6 12489.6 12972.7 13094.0 13119.1 11249.4 11382.5 11452.4 11563.4 11656.5 3762.3 3769.0 3776.2 3783.8 3800.5 71385.0 72028.6 72089.3 72368.0 73730.0 9659.6 9774.7 9785.4 9801.5 9967.7 19591.6 19612.5 19628.9 19655.3 19666.8 8060.1 8195.9 8305.5 8350.5 8497.3 4382.3 4420.6 4450.5 4503.8 4656.4 3531.3 3605.4 3612.5 3646.8 3659.9 590.2 598.1 599.3 608.7 618.6 703.4 714.4 715.1 723.9 730.8 8732.2 9045.0 9171.2 9176.1 9334.2 724.7 725.6 728.2 734.0 748.8 4221.2 4541.3 4579.4 4596.0 4667.8 71349.3 75216.6 76225.6 77695.2 78507.6 66528.3 66823.7 67213.7 67695.0 67841.0 59176.4 59720.0 59812.4 60672.7 60699.4 8587.7 8792.8 8793.0 8991.7 9083.1 41863.4 41896.4 42200.0 43282.6 43436.0 51217.5 51696.7 52932.7 53108.2 53645.5 10422.7 10446.6 10816.4 10901.1 11929.2 12587.9 13502.6 13800.8 14388.8 14773.2 12842.7 13021.3 13078.3 13182.7 13841.9 13080.0 13695.8 13857.1 14490.3 14877.9 12950.4 12981.9 13769.3 14201.4 14801.9 10626.8 10737.9 10889.0 10956.9 10970.3 9996.6 10294.2 10309.2 10493.0 10554.7 09:31:27 INFO - PROCESS | 12599 | -------- Summary: end -------- 09:31:27 INFO - PROCESS | 12599 | 09:31:27 INFO - TEST-INFO | 12599: exit 0 09:31:27 INFO - TEST-OK | dromaeo_css | took 1114565ms 09:31:27 INFO - TEST-START | kraken 09:31:27 INFO - operating with platform_type : linux_ 09:31:27 INFO - Initialising browser for kraken test... 09:31:27 INFO - TEST-INFO | started process 12957 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoIbaEy/profile http://localhost:57548/getInfo.html) 09:31:28 INFO - PROCESS | 12957 | 1482687088351 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:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < 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 09:31:32 INFO - PROCESS | 12957 | __metrics Screen width/height:1600/1200 09:31:32 INFO - PROCESS | 12957 | colorDepth:24 09:31:32 INFO - PROCESS | 12957 | Browser inner width/height: 1024/697 09:31:32 INFO - PROCESS | 12957 | __metrics 09:31:32 ERROR - PROCESS | 12957 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3256: TypeError: this.tabs is undefined 09:31:33 INFO - TEST-INFO | 12957: exit 0 09:31:33 INFO - Browser initialized. 09:31:33 INFO - Running cycle 1/1 for kraken test... 09:31:33 INFO - TEST-INFO | started process 13080 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoIbaEy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:31:45 INFO - PROCESS | 13080 | RSS: Main: 154382336 09:31:45 INFO - PROCESS | 13080 | 09:31:48 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57548/tests/kraken/driver.html?testName=audio-beat-detection) 09:31:49 INFO - PROCESS | 13080 | RSS: Main: 160686080 09:31:49 INFO - PROCESS | 13080 | 09:31:51 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57548/tests/kraken/driver.html?testName=audio-dft) 09:31:52 INFO - PROCESS | 13080 | RSS: Main: 159408128 09:31:52 INFO - PROCESS | 13080 | 09:31:55 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57548/tests/kraken/driver.html?testName=audio-fft) 09:31:55 INFO - PROCESS | 13080 | RSS: Main: 155459584 09:31:55 INFO - PROCESS | 13080 | 09:31:58 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57548/tests/kraken/driver.html?testName=audio-oscillator) 09:31:58 INFO - PROCESS | 13080 | RSS: Main: 156512256 09:31:58 INFO - PROCESS | 13080 | 09:32:00 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57548/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:32:01 INFO - PROCESS | 13080 | RSS: Main: 157089792 09:32:01 INFO - PROCESS | 13080 | 09:32:04 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57548/tests/kraken/driver.html?testName=imaging-darkroom) 09:32:05 INFO - PROCESS | 13080 | RSS: Main: 160817152 09:32:05 INFO - PROCESS | 13080 | 09:32:08 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57548/tests/kraken/driver.html?testName=imaging-desaturate) 09:32:09 INFO - PROCESS | 13080 | RSS: Main: 158457856 09:32:09 INFO - PROCESS | 13080 | 09:32:12 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57548/tests/kraken/driver.html?testName=json-parse-financial) 09:32:12 INFO - PROCESS | 13080 | RSS: Main: 159830016 09:32:12 INFO - PROCESS | 13080 | 09:32:14 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57548/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:32:14 INFO - PROCESS | 13080 | RSS: Main: 159293440 09:32:14 INFO - PROCESS | 13080 | 09:32:16 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:32:17 INFO - PROCESS | 13080 | RSS: Main: 159485952 09:32:17 INFO - PROCESS | 13080 | 09:32:19 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:32:20 INFO - PROCESS | 13080 | RSS: Main: 159420416 09:32:20 INFO - PROCESS | 13080 | 09:32:22 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:32:23 INFO - PROCESS | 13080 | RSS: Main: 159354880 09:32:23 INFO - PROCESS | 13080 | 09:32:26 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:32:26 INFO - PROCESS | 13080 | RSS: Main: 159846400 09:32:26 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | Cycle 1(1): loaded http://localhost:57548/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57548/tests/kraken/driver.html?testName=ai-astar) 09:32:28 INFO - PROCESS | 13080 | RSS: Main: 161128448 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | __start_tp_report 09:32:28 INFO - PROCESS | 13080 | _x_x_mozilla_page_load 09:32:28 INFO - PROCESS | 13080 | _x_x_mozilla_page_load_details 09:32:28 INFO - PROCESS | 13080 | |i|pagename|runs| 09:32:28 INFO - PROCESS | 13080 | |0;ai-astar;104;111;112;113;112;111;112;111;111;111 09:32:28 INFO - PROCESS | 13080 | |1;audio-beat-detection;146;122;121;168;132;122;128;120;139;121 09:32:28 INFO - PROCESS | 13080 | |2;audio-dft;161;162;163;162;163;168;167;163;165;162 09:32:28 INFO - PROCESS | 13080 | |3;audio-fft;77;75;75;114;76;78;76;76;100;77 09:32:28 INFO - PROCESS | 13080 | |4;audio-oscillator;83;85;103;84;84;83;99;84;84;109 09:32:28 INFO - PROCESS | 13080 | |5;imaging-gaussian-blur;119;118;117;118;118;118;117;117;117;118 09:32:28 INFO - PROCESS | 13080 | |6;imaging-darkroom;148;148;149;147;147;148;147;147;147;148 09:32:28 INFO - PROCESS | 13080 | |7;imaging-desaturate;97;97;96;96;96;97;96;97;96;97 09:32:28 INFO - PROCESS | 13080 | |8;json-parse-financial;56;57;59;57;59;58;57;60;57;57 09:32:28 INFO - PROCESS | 13080 | |9;json-stringify-tinderbox;68;74;69;69;69;69;68;69;68;68 09:32:28 INFO - PROCESS | 13080 | |10;stanford-crypto-aes;84;83;82;83;84;85;83;82;83;92 09:32:28 INFO - PROCESS | 13080 | |11;stanford-crypto-ccm;129;126;127;140;135;127;130;138;168;137 09:32:28 INFO - PROCESS | 13080 | |12;stanford-crypto-pbkdf2;177;174;171;172;171;173;172;168;175;166 09:32:28 INFO - PROCESS | 13080 | |13;stanford-crypto-sha256-iterative;67;73;67;65;63;67;68;63;63;64 09:32:28 INFO - PROCESS | 13080 | __end_tp_report 09:32:28 INFO - PROCESS | 13080 | __start_cc_report 09:32:28 INFO - PROCESS | 13080 | _x_x_mozilla_cycle_collect,741 09:32:28 INFO - PROCESS | 13080 | __end_cc_report 09:32:28 INFO - PROCESS | 13080 | __startTimestamp1482687148887__endTimestamp 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | ------- Summary: start ------- 09:32:28 INFO - PROCESS | 13080 | Number of tests: 14 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.50 stddev:2.49 (2.2%) stddev-sans-first:0.73 09:32:28 INFO - PROCESS | 13080 | Values: 104.0 111.0 112.0 113.0 112.0 111.0 112.0 111.0 111.0 111.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:130.00 stddev:15.43 (11.9%) stddev-sans-first:15.50 09:32:28 INFO - PROCESS | 13080 | Values: 146.0 122.0 121.0 168.0 132.0 122.0 128.0 120.0 139.0 121.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#2] audio-dft Cycles:10 Average:163.60 Median:163.00 stddev:2.32 (1.4%) stddev-sans-first:2.26 09:32:28 INFO - PROCESS | 13080 | Values: 161.0 162.0 163.0 162.0 163.0 168.0 167.0 163.0 165.0 162.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#3] audio-fft Cycles:10 Average:82.40 Median:76.00 stddev:13.41 (17.6%) stddev-sans-first:14.08 09:32:28 INFO - PROCESS | 13080 | Values: 77.0 75.0 75.0 114.0 76.0 78.0 76.0 76.0 100.0 77.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#4] audio-oscillator Cycles:10 Average:89.80 Median:84.00 stddev:9.87 (11.8%) stddev-sans-first:10.16 09:32:28 INFO - PROCESS | 13080 | Values: 83.0 85.0 103.0 84.0 84.0 83.0 99.0 84.0 84.0 109.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#5] imaging-gaussian-blur Cycles:10 Average:117.70 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.53 09:32:28 INFO - PROCESS | 13080 | Values: 119.0 118.0 117.0 118.0 118.0 118.0 117.0 117.0 117.0 118.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#6] imaging-darkroom Cycles:10 Average:147.60 Median:148.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 09:32:28 INFO - PROCESS | 13080 | Values: 148.0 148.0 149.0 147.0 147.0 148.0 147.0 147.0 147.0 148.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#7] imaging-desaturate Cycles:10 Average:96.50 Median:97.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 09:32:28 INFO - PROCESS | 13080 | Values: 97.0 97.0 96.0 96.0 96.0 97.0 96.0 97.0 96.0 97.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.50 stddev:1.25 (2.2%) stddev-sans-first:1.17 09:32:28 INFO - PROCESS | 13080 | Values: 56.0 57.0 59.0 57.0 59.0 58.0 57.0 60.0 57.0 57.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.00 stddev:1.79 (2.6%) stddev-sans-first:1.86 09:32:28 INFO - PROCESS | 13080 | Values: 68.0 74.0 69.0 69.0 69.0 69.0 68.0 69.0 68.0 68.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:83.50 stddev:2.92 (3.5%) stddev-sans-first:3.10 09:32:28 INFO - PROCESS | 13080 | Values: 84.0 83.0 82.0 83.0 84.0 85.0 83.0 82.0 83.0 92.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#11] stanford-crypto-ccm Cycles:10 Average:135.70 Median:136.00 stddev:12.44 (9.1%) stddev-sans-first:12.95 09:32:28 INFO - PROCESS | 13080 | Values: 129.0 126.0 127.0 140.0 135.0 127.0 130.0 138.0 168.0 137.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.90 Median:172.50 stddev:3.21 (1.9%) stddev-sans-first:2.83 09:32:28 INFO - PROCESS | 13080 | Values: 177.0 174.0 171.0 172.0 171.0 173.0 172.0 168.0 175.0 166.0 09:32:28 INFO - PROCESS | 13080 | 09:32:28 INFO - PROCESS | 13080 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:67.00 stddev:3.13 (4.7%) stddev-sans-first:3.30 09:32:28 INFO - PROCESS | 13080 | Values: 67.0 73.0 67.0 65.0 63.0 67.0 68.0 63.0 63.0 64.0 09:32:28 INFO - PROCESS | 13080 | -------- Summary: end -------- 09:32:28 INFO - PROCESS | 13080 | 09:32:29 INFO - TEST-INFO | 13080: exit 0 09:32:29 INFO - TEST-OK | kraken | took 62072ms 09:32:29 INFO - SUITE-END | took 1176s 09:32:30 INFO - Completed test suite (00:19:36) 09:32:30 INFO - javascript benchmark 09:32:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6965.378155536623, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.3549972255682, 3175.2007410452893, 3179.841007949602, 3205.727513161381, 3208.9197770055753, 5296.09966305728, 5390.245365648812, 5410.891782164359, 5414.485623865731, 5454.509094181532, 5341.4658534146665, 5372.677639341642, 5438.864028399292, 5458.426865179159, 5501.0648189807725, 4820.325154478376, 4852.078105160008, 4886.755662216893, 4971.229459433794, 4973.452920178777, 4875.975620121894, 4991.301217829507, 4999.525045120708, 5006.424261209971, 5006.774695138727, 4809.519048095198, 4931.112399768041, 4938.283948827418, 4991.550760431579, 5014.598832093424, 4834.323194752737, 4856.15017371962, 4870.65905386623, 4904.4605093439695, 4994.87512812179, 4867.415910090776, 4872.683275587111, 4893.941425669579, 4920.827771027997, 4923.532264434909, 5410.242566040757, 5421.457854214586, 5422.15956526739, 5424.213489044106, 5453.563714902838, 5633.0987042073075, 5686.715664216814, 5701.2018317435595, 5713.085906254979, 5732.656040637575, 5804.187413762077, 5806.941930580682, 5809.6804675742815, 5825.7378417971295, 5877.735501902424, 5397.325334333208, 5411.404745477994, 5414.3232095988005, 5415.079436495806, 5415.16065009928, 5800.59395842287, 5830.154627578977, 5837.124431335334, 5844.035734103911, 5937.050071988461, 5612.354579223442, 5641.746121424546, 5643.7742490299925, 5690.231818704422, 5699.857503562361, 5701.0, 5714.857128571736, 5765.769369225268, 5771.797987070517, 5792.681402522818, 5649.4633009864, 5671.602987790898, 5675.262215911905, 5739.339975902742, 5753.395893431213, 5714.571407144397, 5731.914021289684, 5748.798792042258, 5767.163761254678, 5784.566157538202, 4348.326009468537, 4350.521442641307, 4366.585174408489, 4379.846705365297, 4393.538678438782, 3887.9805600971813, 3910.0420397002918, 3914.041059940333, 3940.7438516496336, 3944.467496887941, 5327.653702509325, 5353.705546194997, 5409.675419474844, 5420.322459692538, 5506.476884695948, 5785.739641716133, 5839.0, 5858.238429004202, 5865.677387743715, 5919.585629005928, 5398.271233383522, 5437.13005919051, 5468.152436372368, 5490.588205884575, 5513.283273174462, 4726.88182795426, 4755.690880092783, 4779.617630589544, 4848.175810112219, 4904.0, 5975.910361344583, 6036.788712395045, 6053.4854537363935, 6081.543884208702, 6082.057281121433, 5809.65142091459, 5817.301923769175, 5823.592348535732, 5823.621464604787, 5834.0, 5903.380145084704, 5929.110633405024, 6084.300305464752, 6099.573029887865, 6100.267967843887, 5633.2395126657375, 5679.659220446786, 5688.089905615082, 5729.828105156852, 5787.421257874179, 5804.158397032491, 5844.12338149281, 5848.824535263949, 5860.472557469848, 5860.677662728591, 5552.111662134039, 5668.093105103164, 5705.172749951317, 5737.827865164052, 5740.655560666373, 5578.721063946868, 5669.149627555735, 5672.092465205547, 5674.886502270014, 5775.624584402, 5675.460831221028, 5702.515286200553, 5720.742566584597, 5723.198752174616, 5725.856853578527, 5600.747966341443, 5642.040853055072, 5734.05388110957, 5778.508826749773, 5794.159846822272, 5380.569554435558, 5400.648957817729, 5483.533899618577, 5536.806211782729, 5584.86037849057], "value": 5326.902155856053, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.7059073395734, 562.9296337957755, 566.9773209071637, 568.5281216590231, 574.7815829984605, 40530.18939621206, 42826.35760463595, 42903.141937161236, 46341.30488042674, 46471.99999999991, 44354.33468497975, 44623.330650040276, 44671.10657786842, 44765.328520072224, 44785.55214447855, 8643.222110010081, 8801.779955501099, 8803.691870784523, 8824.249938755214, 10038.795344558626, 10154.695359139238, 10250.282480226388, 10264.89735102647, 10274.845877311845, 10277.845832312483, 10366.948165259164, 10395.012473814975, 10396.792064158712, 10397.220208484394, 10415.427151506665, 10301.918298578654, 10359.896401035969, 10360.0, 10360.222983276284, 10368.896311036944, 10211.285210035301, 10271.486425678686, 10285.331453455576, 10294.948525257325, 10297.12474439666, 37466.0, 37638.43542346867, 38030.23939521194, 38044.429333560016, 38134.0, 3916.7649941003633, 4057.513098428178, 4060.4315395844606, 4065.166640838621, 4075.7758323292205, 28761.424771504662, 29380.559291610643, 29381.559276610868, 29499.0, 29908.0, 3973.821178046966, 4046.190761847613, 4234.343676730112, 4260.169266992938, 4274.252005898955, 313294.0, 313351.0, 314067.0, 314952.0, 315172.99999999773, 50998.49001509937, 51015.489845101816, 51022.48977510177, 51316.743416283425, 51470.48529514657, 52558.73720631372, 52962.20556691653, 53068.75449964071, 53266.73366633142, 53811.73094134504, 15006.174660393564, 15371.0, 15456.459023934332, 15493.302801373966, 15653.608659783597, 11870.406479675981, 12114.69713257158, 12123.45444454984, 12187.0, 12403.193792403465, 16670.916645416695, 16816.915915420588, 16853.157342132843, 16882.324707011827, 16884.577885552713, 6675.3324667533825, 6680.0, 6786.864262714718, 6806.285340039224, 6921.619310937846, 893.5755516129786, 902.873597696323, 906.7823722306599, 907.6823111910778, 939.5396255834728, 29407.713839827422, 29465.02139814519, 29584.189501997276, 30212.84893575518, 30987.69012309893, 1075.2956813287153, 1077.3159044006954, 1092.7377429416883, 1107.6953837694698, 1148.9831499123336, 15699.0, 16196.838031619533, 16291.918540407221, 16378.918105409635, 16426.83573164277, 480764.0, 482871.0, 488258.0, 490886.0, 730365.0, 52152.0, 52430.0, 52526.737366312926, 52796.20805687918, 52832.73583632057, 1351.8986076044337, 1361.5030513862368, 1385.6328073060647, 1386.3969173409398, 1404.7331007108617, 27555.31111722223, 27601.58597620997, 28198.71801281961, 28446.57330140008, 28999.56500652534, 44468.332975004756, 44758.77620611876, 44838.86127971011, 45299.0, 45346.0, 343565.0, 345222.0, 345955.0, 346595.0, 346721.0, 23496.412589685395, 23739.16912908109, 24974.0, 25042.3739406509, 25209.487430754212, 40633.79683101566, 40689.982750431474, 41217.0, 41444.0, 42199.367009494264, 34461.0, 34710.65289347074, 34749.0, 34934.82532587321, 34935.12662183466, 33465.0, 33744.49383259204, 33806.49290260697, 33930.151746206546, 34098.829505852314, 34336.141596460286, 34798.0, 35269.82365088158, 35499.64500354963, 35670.82164589161, 28255.29361765972, 31263.218419538785, 31331.84334078406, 31404.685953141092, 31494.370112598077, 24346.51306973886, 24384.390390240387, 24450.877745611157, 24455.510889781748, 24616.876915615307], "value": 18952.700544143034, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.8807813616849, 394.08965290179685, 402.7060246020405, 404.6863680647498, 422.953475117737, 16492.917535412336, 16498.505044848673, 17655.20551575179, 18910.338138165167, 19116.904415477922, 12093.758124837497, 12138.271703697785, 12335.259884406942, 12547.121701480877, 12701.428435720392, 4965.3296804931415, 5065.898682026357, 5117.769700363474, 5124.461931497195, 5138.58891288696, 5016.27264046713, 5059.595232381401, 5378.2470454136455, 5701.828945131632, 5742.023855944479, 5691.4877661010505, 5692.943070569303, 5701.885962280752, 5707.800226992056, 5727.226824378718, 5502.944970550283, 5691.0, 5692.17463467799, 5709.400512946122, 5739.827805165852, 5150.0472412603785, 5150.27896094547, 5157.355330583677, 5173.5602473789395, 5214.930939157502, 13660.385282662304, 13705.725885482334, 13808.17150970935, 13858.376373063236, 15946.043237405676, 2951.070412819955, 2995.6255468066493, 3036.0852734419727, 3049.1462390530687, 3073.3545955349405, 6200.658963756991, 10136.746581335452, 10205.18358531323, 10307.608472856144, 10770.40762758048, 1868.1033104110104, 1953.8339241164517, 1968.3602829080605, 1972.6911446656704, 1987.0, 69588.0, 71205.64397178033, 72641.0, 72803.0, 73427.0, 9085.0, 9419.199368053793, 9548.140667339972, 9636.277279204087, 10111.848322275171, 13046.608601741962, 13162.934185329012, 13165.144265622705, 13180.934095329461, 13208.009399295091, 6936.271691472423, 7125.786226413215, 7189.604571748604, 7375.963120184365, 7420.146683131509, 6957.547759395724, 6959.234484206733, 7135.89296160558, 7142.642867856586, 7196.136463624399, 3975.7813320267664, 4012.25773231953, 4109.97945010279, 4170.186813571325, 4228.534861165269, 1960.3334866145578, 1962.4112766170092, 1995.2418081129367, 2013.7180794688754, 2051.661475856497, 866.7703058689452, 897.1387468030633, 902.4449963272672, 906.6146887572755, 939.3940908114228, 17684.46946591604, 17895.552611184823, 18008.36970706019, 18351.449456516326, 18608.255669773058, 886.5833058462513, 887.32563251929, 896.2605850173633, 900.1853322742928, 907.3059109781022, 10343.0, 10343.797009334538, 10577.735556611146, 10582.417967011734, 10616.469176541143, 194759.0, 198326.0, 430707.0, 441923.0, 442625.0, 73309.0, 73552.0, 73989.63005185047, 74243.0, 78368.0, 979.1383582447543, 1016.1261315268863, 1043.436544266103, 1054.9103326217205, 1057.038095333243, 16033.438829640907, 17127.0, 17182.570435738708, 17262.482125536255, 17515.21181546808, 37368.0, 37959.0, 38647.42028869625, 39064.41403378895, 39281.41077883891, 51475.0, 52003.21995170151, 52333.0, 53376.199357008896, 54486.0, 8263.132371100948, 8268.545230012407, 8269.95865020695, 8451.746447606582, 8558.614862331086, 9195.908040919772, 11911.285322880654, 12498.750024999266, 12499.687507812378, 12682.556110536456, 10295.94852025735, 10708.678739637824, 10722.678319650424, 10744.785104297714, 11181.888181118411, 10488.68533943983, 10611.840822387825, 10629.521671524955, 10679.0, 10892.455377231265, 10512.0, 10637.042666160083, 10705.250632455653, 10709.250352475252, 11353.772924541297, 8157.0, 8249.216324449168, 8259.256666899768, 8610.569471526273, 8681.479111253093, 9150.588223529823, 10464.162866970471, 10508.579656813949, 10521.053105220566, 10870.184736144663], "value": 8388.126441192822, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1367.4393498665543, 1373.0320124312361, 1373.9313034348281, 1377.4696741754422, 1379.4551152294837, 7605.353544948686, 7848.529088254709, 8115.310198633116, 8593.441426307285, 8630.870536941942, 8038.8794168087525, 8275.420720549564, 8309.584520773968, 8400.3279737621, 8532.53071081092, 2237.6196046672103, 2261.208576998046, 2293.6100862853355, 2302.9309120726407, 2303.5968705476557, 2059.5057186275335, 2129.361191642509, 2238.1942500699734, 2292.037344315383, 2298.2990187992655, 2199.83501237407, 2286.1198438601177, 2290.450291929933, 2298.689676893615, 2300.3214051854743, 2282.2012295696454, 2293.598620241451, 2307.7807608277353, 2316.247219653619, 2320.918767843126, 2362.858228506278, 2371.620540713495, 2371.893264803071, 2393.6170212765874, 2407.0612461140167, 8339.416240863142, 8446.282066024396, 8454.408191426603, 8518.27594654455, 8541.786455338604, 5091.516305950929, 5233.790648374061, 5236.581073514109, 5246.0, 5274.498922602346, 3237.498187780874, 3378.7803792753393, 3424.0583839444103, 3424.3151369725906, 3453.6028356739043, 1316.6181807275877, 1362.0, 1388.7569675306781, 1398.7202559488144, 1410.1891412437787, 10528.526216320284, 10923.563057477691, 10952.780944381067, 11116.555337786398, 11499.19505634606, 4434.5343738907595, 4516.88707782308, 4709.599684026827, 4723.197056500403, 4730.479647238801, 9722.95138524317, 9809.411435313761, 9817.656382026595, 9825.164860986895, 9856.112949834405, 5304.071787437183, 5305.204219367049, 5310.097283461821, 5315.840524784263, 5317.122674758699, 4662.254039353687, 4723.197056500403, 4797.352357431771, 4838.612910967114, 4891.730954797449, 3679.889603311905, 3748.306563285801, 3766.114962983697, 3791.696664266852, 3818.3508803503473, 1185.9762804743857, 1194.9940250298694, 1200.9399530023463, 1213.8968187704145, 1218.61004478566, 934.8924873639619, 952.2382094324652, 957.1051067252141, 959.4579062829479, 973.3770386952356, 5650.7739690412745, 5722.942770572325, 5783.421657834267, 5986.004804320898, 6010.068439391901, 922.262190247799, 944.40502483435, 960.9087136722001, 972.6838777397375, 1011.4285428732743, 3834.9041273968373, 4096.344584866407, 4102.2000709861295, 4141.171765646883, 4151.27352713274, 9856.704298871045, 10166.898331016742, 10256.58973641046, 10276.434796086287, 10287.948560257151, 9305.162535371848, 9801.11789938909, 10086.49567521621, 10229.43738094412, 10237.43694096817, 7803.180666030099, 8002.319802816709, 8010.319122874621, 8015.198480151938, 8022.759317220492, 5382.416578994339, 5387.568994480511, 5546.195801608825, 5562.082256427644, 5601.5238704710555, 8924.241439477717, 9261.76845578839, 9296.07039296065, 9412.15290623847, 9432.43405395651, 9318.72043838686, 9457.810843782949, 9476.47879366614, 9488.95255523718, 9727.12455878946, 7032.12098487689, 7218.0, 7226.168990566152, 7537.660805263667, 7611.619419029137, 8179.263866252066, 8194.139615340304, 8229.094799571923, 8234.629441675019, 8271.255586997198, 7174.641267936687, 7246.0, 7278.235785242473, 7534.510256833289, 7568.583727894798, 7192.496525243183, 7309.853802924018, 7318.96340518294, 7321.0, 7388.556686598821, 6395.264544577433, 6536.869262614626, 6555.147830781968, 6558.409743123142, 6617.7683781067435, 4929.186684197068, 4970.403551573834, 4981.875453113556, 5054.140796064751, 5136.512031357015, 5014.4484106747495, 5095.949040509696, 5170.0, 5186.066508028591, 5205.271262023395], "value": 4496.451286823068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.62261559286145, 387.94568760373534, 393.6260552475147, 396.2906397548389, 397.2710077008689, 5188.169892817141, 5325.840224793253, 5367.684917195288, 5404.891902161954, 5434.483724046209, 5137.434882162968, 5220.817271395502, 5253.6322457428, 5276.630635855482, 5283.8150664726745, 4230.7038507304505, 4249.723767955088, 4273.551277115905, 4281.373078230268, 4284.957150428487, 4114.732542384735, 4211.157768446307, 4255.787210639471, 4310.267254566716, 4322.610965013148, 4224.471941007374, 4225.514065882417, 4240.194363071007, 4247.129338485618, 4247.447831781879, 4248.299030659938, 4257.531671516131, 4270.658347332222, 4286.807093680792, 4287.292596721537, 3900.8350365547394, 3902.2195560887653, 3904.414337849317, 3907.081835768592, 3920.6863450924143, 5046.873828154289, 5123.666961647481, 5123.871903202413, 5133.152065256505, 5133.563647090002, 4835.371401717789, 4856.077345304416, 4874.951250487521, 4882.902341953141, 4895.510448955076, 5435.592330575183, 5527.198556209327, 5552.972235138798, 5554.50009499143, 5574.581906356999, 4193.643540299078, 4202.642775364097, 4214.072903961123, 4217.873463796091, 4220.430241917333, 5765.789184271308, 5796.449337312949, 5817.709114544298, 5846.327672317653, 5861.2087368205175, 4347.434833471629, 4351.9564804352185, 4354.0, 4364.214441400578, 4364.682841040832, 3962.980185099114, 4165.4795999459975, 4185.346788018715, 4199.0342221289275, 4200.600942910419, 4736.289556566542, 4756.334113224151, 4770.0936822003705, 4773.0453909218295, 4791.305260737243, 5657.462541058593, 5673.943260567424, 5685.204071430003, 5701.458361455655, 5709.457601527848, 1385.4942945824903, 1401.7967394727707, 1405.8031875537433, 1406.542873566095, 1422.5732280315865, 953.5327689432129, 967.8403063494585, 976.9804603907882, 987.9527700637348, 990.3067852503276, 5178.97410512945, 5182.378114626269, 5208.687478751287, 5233.947660523422, 5269.815556455505, 5539.086050801653, 5586.888262234733, 5591.636543624732, 5626.887462250732, 5630.155476678531, 5088.414832294259, 5095.15929871567, 5179.715115668599, 5214.661047031931, 5226.125630902988, 2977.8064425812254, 2981.807277089138, 2998.3553535989868, 3005.6693763685976, 3005.8497075146156, 5727.680289482766, 5770.336411312669, 5843.0, 5873.794417195378, 5874.236349274567, 5414.512693857571, 5742.7415766290615, 5754.63353937951, 5757.971210144006, 5770.711464426762, 5784.074548072373, 5846.684495988368, 5855.414458554195, 5882.676452795137, 5894.646321220741, 5292.311999440049, 5502.312210973629, 5532.446755324436, 5599.972000139973, 5635.239242702182, 5467.371252305877, 5564.276644036249, 5571.164325351229, 5574.216250799705, 5610.747516361856, 5783.392743761844, 5791.681457519709, 5813.912791308218, 5843.2695913010875, 5853.28316519022, 3925.371940489508, 4041.7170798043853, 4141.316682747313, 4173.8956526087095, 4184.81168347419, 5724.540242238202, 5753.827385178452, 5764.13537969308, 5768.884622307446, 5782.595218334676, 5529.944700552943, 5594.496495315441, 5601.571599242167, 5609.4390560943575, 5613.12996485529, 5544.556435485071, 5601.551875850004, 5605.299337582802, 5613.719314034363, 5622.409646987007, 5573.7213139343685, 5595.132754423071, 5605.5515558756015, 5625.437456254342, 5638.85902852432, 5466.262054622575, 5641.323041234914, 5657.773689052557, 5671.773129074791, 5698.287714035746, 5589.189567512606, 5593.664380137205, 5608.298962629671, 5612.270404847343, 5623.3814280428305], "value": 4299.927872259455, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.5232099135561, 761.6458346868704, 790.3361176611645, 831.3432388413154, 853.71827296992, 14546.418143274283, 14973.92513037436, 15001.924990375033, 15062.397504099836, 15969.121698306588, 13266.270355130464, 14155.504557340495, 14311.713765724679, 14326.068805527633, 14374.49689260876, 806.1978331560106, 825.0882774534143, 826.1408135539032, 827.0819390476566, 828.3456069704926, 813.9796505087361, 824.2952275804191, 824.4146655874306, 827.0819390476566, 827.1191181391798, 838.7567605394428, 839.4291881520563, 839.7396806989822, 847.2459511035183, 853.4239388412827, 826.6817275349034, 828.9005319361655, 831.3806214370272, 840.1220724343045, 840.8024114333127, 606.2997238189897, 606.5087279303779, 614.9354317796611, 618.8421952402109, 619.2414292491707, 12930.48278068876, 12936.870631293661, 13551.796723049161, 13676.111052781538, 13697.041207115506, 12372.56696015644, 12489.562865299762, 12972.675683107904, 13094.0, 13119.081664283503, 11249.381284029376, 11382.544698212061, 11452.427378631119, 11563.364014979174, 11656.533738650529, 3762.3039737648623, 3768.9811550942068, 3776.150366167618, 3783.848646054182, 3800.4869342638667, 71385.0, 72028.63985680038, 72089.27910720931, 72368.0, 73730.0, 9659.565319560637, 9774.657886973922, 9785.412875227508, 9801.460919649488, 9967.651132210338, 19591.608167836563, 19612.509687257647, 19628.901855490632, 19655.312064077687, 19666.803331966497, 8060.113387527367, 8195.877061844078, 8305.46014509055, 8350.498970061693, 8497.277731392776, 4382.320740285261, 4420.624246938982, 4450.46594408673, 4503.842365517191, 4656.441227052707, 3531.346700860349, 3605.387084195693, 3612.548431446069, 3646.835892384849, 3659.908502287464, 590.2385922160365, 598.07000114821, 599.340725202274, 608.7321578505532, 618.5639124417365, 703.4442790195791, 714.3713532091726, 715.1096884378982, 723.8624661314334, 730.8392153726172, 8732.214100730966, 9045.0, 9171.174594286545, 9176.08239176077, 9334.20659243972, 724.7028718225503, 725.5646612032843, 728.2462651156134, 734.0494060192065, 748.7500062312715, 4221.2401767681495, 4541.2733962565835, 4579.381783459257, 4595.977020114945, 4667.789949452283, 71349.28650713427, 75216.62391688007, 76225.61887190529, 77695.22304776993, 78507.60746196233, 66528.33471665221, 66823.66588167028, 67213.66393168003, 67695.0, 67841.0, 59176.408235917086, 59720.0, 59812.40187598155, 60672.696636516535, 60699.393006069375, 8587.65649374018, 8792.824143517222, 8792.977615148424, 8991.685291014783, 9083.137101975302, 41863.37204941989, 41896.371554427315, 42200.0, 43282.56717432785, 43436.0, 51217.48782512127, 51696.741516292175, 52932.73533632307, 53108.20337695015, 53645.463545364044, 10422.687319380431, 10446.63436779709, 10816.4050977197, 10901.127909767041, 11929.224600400947, 12587.87412125867, 13502.594922152352, 13800.792988104986, 14388.784168237275, 14773.199754489273, 12842.745958890291, 13021.283829389475, 13078.28069456189, 13182.736345273233, 13841.930790345983, 13080.0, 13695.794563081761, 13857.099288546213, 14490.275486225857, 14877.925610371878, 12950.417231224807, 12981.870181298065, 13769.31153442344, 14201.360938757987, 14801.851981480479, 10626.787464250518, 10737.89262107369, 10889.0, 10956.945215273872, 10970.341779493256, 9996.550155243178, 10294.220560448786, 10309.22680798928, 10493.0, 10554.683359499228], "value": 6974.039160665067, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 111.0, 112.0, 113.0, 112.0, 111.0, 112.0, 111.0, 111.0, 111.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 122.0, 121.0, 168.0, 132.0, 122.0, 128.0, 120.0, 139.0, 121.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 163.0, 162.0, 163.0, 168.0, 167.0, 163.0, 165.0, 162.0], "value": 163.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 114.0, 76.0, 78.0, 76.0, 76.0, 100.0, 77.0], "value": 82.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 103.0, 84.0, 84.0, 83.0, 99.0, 84.0, 84.0, 109.0], "value": 89.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 118.0, 117.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 148.0, 149.0, 147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 148.0], "value": 147.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 96.0, 96.0, 97.0, 96.0, 97.0, 96.0, 97.0], "value": 96.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 59.0, 57.0, 59.0, 58.0, 57.0, 60.0, 57.0, 57.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 69.0, 69.0, 69.0, 69.0, 68.0, 69.0, 68.0, 68.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 82.0, 83.0, 84.0, 85.0, 83.0, 82.0, 83.0, 92.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 126.0, 127.0, 140.0, 135.0, 127.0, 130.0, 138.0, 168.0, 137.0], "value": 135.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 174.0, 171.0, 172.0, 171.0, 173.0, 172.0, 168.0, 175.0, 166.0], "value": 171.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 67.0, 65.0, 63.0, 67.0, 68.0, 63.0, 63.0, 64.0], "value": 66.0, "unit": "score"}]}]} 09:32:30 INFO - Return code: 0 09:32:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:32:30 ERROR - # TBPL SUCCESS # 09:32:30 INFO - Running post-action listener: _resource_record_post_action 09:32:30 INFO - [mozharness: 2016-12-25 17:32:30.199123Z] Finished run-tests step (success) 09:32:30 INFO - Running post-run listener: _resource_record_post_run 09:32:30 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 8192; Write bytes: 410570752; Read time: 24; Write time: 472976 09:32:30 INFO - TinderboxPrint: CPU usage
13.4% 09:32:30 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 09:32:30 INFO - TinderboxPrint: I/O write bytes / time
410,570,752 / 472,976 09:32:30 INFO - TinderboxPrint: CPU idle
8,245.9 (86.5%) 09:32:30 INFO - TinderboxPrint: CPU user
1,218.5 (12.8%) 09:32:30 INFO - TinderboxPrint: Swap in / out
0 / 0 09:32:30 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 116002816; Read time: 0; Write time: 111468 09:32:30 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 293662720; Read time: 16; Write time: 361480 09:32:30 INFO - Running post-run listener: _upload_blobber_files 09:32:30 INFO - Blob upload gear active. 09:32:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:32:30 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 09:32:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:32:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:32:31 INFO - (blobuploader) - INFO - Open directory for files ... 09:32:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:32:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:32:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:32 INFO - (blobuploader) - INFO - Done attempting. 09:32:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:32:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:32:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:35 INFO - (blobuploader) - INFO - Done attempting. 09:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:32:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:36 INFO - (blobuploader) - INFO - Done attempting. 09:32:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:32:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:32:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:32:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:32:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:32:36 INFO - (blobuploader) - INFO - Done attempting. 09:32:36 INFO - (blobuploader) - INFO - Iteration through files over. 09:32:36 INFO - Return code: 0 09:32:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:32:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:32:36 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bcf477ec2a9901af235891e562bb8ac53b6b553d2a1fd704da56b18e8972fe616de83a8b8e366d252f0b55f3682dfe46b04f0c53ea3e46077e8f88303c127b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72c5b9ef5dd25903c13d9fbf031dd372e788f2f2b549a13c5d6b8c5a9c87ae7d70e5289bb995b77e84c86423357e045afaaafc460e483f1072815e5f3194f305", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20cccb5a762eea08d9b53a2e13f8345e4a209eb89f6ded7cc90a463e93282ab804051776a223e67cc1f07aa874f921c610ca11f822fad4a800919609edbaed33", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73b6dfb0ee4efd8237697b846e89b04201a505193090e9186cac8468680327c7b0f49f4e0e6685b3f06f47f429d276b4c7f6e27358f7c620d7fd632771752546"} 09:32:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:32:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:32:36 INFO - Contents: 09:32:36 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bcf477ec2a9901af235891e562bb8ac53b6b553d2a1fd704da56b18e8972fe616de83a8b8e366d252f0b55f3682dfe46b04f0c53ea3e46077e8f88303c127b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72c5b9ef5dd25903c13d9fbf031dd372e788f2f2b549a13c5d6b8c5a9c87ae7d70e5289bb995b77e84c86423357e045afaaafc460e483f1072815e5f3194f305", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20cccb5a762eea08d9b53a2e13f8345e4a209eb89f6ded7cc90a463e93282ab804051776a223e67cc1f07aa874f921c610ca11f822fad4a800919609edbaed33", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73b6dfb0ee4efd8237697b846e89b04201a505193090e9186cac8468680327c7b0f49f4e0e6685b3f06f47f429d276b4c7f6e27358f7c620d7fd632771752546"} 09:32:36 INFO - Running post-run listener: copy_logs_to_upload_dir 09:32:36 INFO - Copying logs to upload dir... 09:32:36 INFO - mkdir: /builds/slave/test/build/upload/logs 09:32:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.383767 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2016-12-25 09:32:36.260481) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 09:32:36.264023) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9bcf477ec2a9901af235891e562bb8ac53b6b553d2a1fd704da56b18e8972fe616de83a8b8e366d252f0b55f3682dfe46b04f0c53ea3e46077e8f88303c127b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72c5b9ef5dd25903c13d9fbf031dd372e788f2f2b549a13c5d6b8c5a9c87ae7d70e5289bb995b77e84c86423357e045afaaafc460e483f1072815e5f3194f305", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20cccb5a762eea08d9b53a2e13f8345e4a209eb89f6ded7cc90a463e93282ab804051776a223e67cc1f07aa874f921c610ca11f822fad4a800919609edbaed33", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73b6dfb0ee4efd8237697b846e89b04201a505193090e9186cac8468680327c7b0f49f4e0e6685b3f06f47f429d276b4c7f6e27358f7c620d7fd632771752546"} build_url:https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011644 build_url: 'https://queue.taskcluster.net/v1/task/GsHbCoojRRaqoChnm8rZLA/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/Firefox/sha512/9bcf477ec2a9901af235891e562bb8ac53b6b553d2a1fd704da56b18e8972fe616de83a8b8e366d252f0b55f3682dfe46b04f0c53ea3e46077e8f88303c127b4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72c5b9ef5dd25903c13d9fbf031dd372e788f2f2b549a13c5d6b8c5a9c87ae7d70e5289bb995b77e84c86423357e045afaaafc460e483f1072815e5f3194f305", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20cccb5a762eea08d9b53a2e13f8345e4a209eb89f6ded7cc90a463e93282ab804051776a223e67cc1f07aa874f921c610ca11f822fad4a800919609edbaed33", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73b6dfb0ee4efd8237697b846e89b04201a505193090e9186cac8468680327c7b0f49f4e0e6685b3f06f47f429d276b4c7f6e27358f7c620d7fd632771752546"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 09:32:36.289950) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:32:36.290289) ========= 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=17051fcd5208b27b50bedfeb000001dd-1482684490.55755-1998815061 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004500 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 09:32:36.376581) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 09:32:36.376923) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 09:32:36.377236) ========= ========= Total master_lag: 0.17 =========