builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-021 starttime: 1467784920.77 results: success (0) buildid: 20160705203035 builduid: 7dd269baf9164a7e8e03346a5c8413c8 revision: d8fa10d0a52eb52ee1fc46f88c37662c71af37a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.766919) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.767359) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.767921) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.842378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.842649) ========= 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=df17445c3590ff7897617a0e00000225-1467783915.276653-605315000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-05 23:02:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 221M=0s 2016-07-05 23:02:01 (221 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.106535 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.974885) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:00.975173) ========= 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=df17445c3590ff7897617a0e00000225-1467783915.276653-605315000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017319 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:01.031167) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 23:02:01.031484) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev d8fa10d0a52eb52ee1fc46f88c37662c71af37a6 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev d8fa10d0a52eb52ee1fc46f88c37662c71af37a6 --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=df17445c3590ff7897617a0e00000225-1467783915.276653-605315000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-05 23:02:01,304 truncating revision to first 12 chars 2016-07-05 23:02:01,304 Setting DEBUG logging. 2016-07-05 23:02:01,304 attempt 1/10 2016-07-05 23:02:01,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/d8fa10d0a52e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-05 23:02:02,155 unpacking tar archive at: fx-team-d8fa10d0a52e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.166536 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-05 23:02:02.212396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:02.212743) ========= script_repo_revision: d8fa10d0a52eb52ee1fc46f88c37662c71af37a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:02.213248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:02.213529) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-05 23:02:02.224549) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-05 23:02:02.224961) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=df17445c3590ff7897617a0e00000225-1467783915.276653-605315000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 23:02:02 INFO - MultiFileLogger online at 20160705 23:02:02 in /builds/slave/test 23:02:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 23:02:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:02:02 INFO - {'append_to_log': False, 23:02:02 INFO - 'base_work_dir': '/builds/slave/test', 23:02:02 INFO - 'blob_upload_branch': 'Fx-Team', 23:02:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:02:02 INFO - 'branch': 'Fx-Team', 23:02:02 INFO - 'buildbot_json_path': 'buildprops.json', 23:02:02 INFO - 'config_files': ('talos/linux_config.py',), 23:02:02 INFO - 'default_actions': ('clobber', 23:02:02 INFO - 'read-buildbot-config', 23:02:02 INFO - 'download-and-extract', 23:02:02 INFO - 'populate-webroot', 23:02:02 INFO - 'create-virtualenv', 23:02:02 INFO - 'install', 23:02:02 INFO - 'run-tests'), 23:02:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:02:02 INFO - 'download_minidump_stackwalk': True, 23:02:02 INFO - 'download_symbols': 'ondemand', 23:02:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:02:02 INFO - 'tooltool.py': '/tools/tooltool.py', 23:02:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:02:02 INFO - '/tools/misc-python/virtualenv.py')}, 23:02:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:02:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:02:02 INFO - 'installer_path': 'installer.exe', 23:02:02 INFO - 'log_level': 'info', 23:02:02 INFO - 'log_name': 'talos', 23:02:02 INFO - 'log_to_console': True, 23:02:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 23:02:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 23:02:02 INFO - 'opt_config_files': (), 23:02:02 INFO - 'pip_index': False, 23:02:02 INFO - 'sps_profile': False, 23:02:02 INFO - 'sps_profile_interval': 0, 23:02:02 INFO - 'suite': 'dromaeojs', 23:02:02 INFO - 'system_bits': '32', 23:02:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:02:02 INFO - 'title': 'talos-linux64-ix-021', 23:02:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:02:02 INFO - 'use_talos_json': True, 23:02:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:02:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:02:02 INFO - 'work_dir': 'build'} 23:02:02 INFO - ##### 23:02:02 INFO - ##### Running clobber step. 23:02:02 INFO - ##### 23:02:02 INFO - Running pre-action listener: _resource_record_pre_action 23:02:02 INFO - Running main action method: clobber 23:02:02 INFO - rmtree: /builds/slave/test/build 23:02:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:02:03 INFO - Running post-action listener: _resource_record_post_action 23:02:03 INFO - ##### 23:02:03 INFO - ##### Finished clobber step (success) 23:02:03 INFO - ##### 23:02:03 INFO - ##### 23:02:03 INFO - ##### Running read-buildbot-config step. 23:02:03 INFO - ##### 23:02:03 INFO - Running pre-action listener: _resource_record_pre_action 23:02:03 INFO - Running main action method: read_buildbot_config 23:02:03 INFO - Using buildbot properties: 23:02:03 INFO - { 23:02:03 INFO - "project": "", 23:02:03 INFO - "product": "firefox", 23:02:03 INFO - "script_repo_revision": "production", 23:02:03 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 23:02:03 INFO - "repository": "", 23:02:03 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 23:02:03 INFO - "buildid": "20160705203035", 23:02:03 INFO - "pgo_build": "True", 23:02:03 INFO - "basedir": "/builds/slave/test", 23:02:03 INFO - "buildnumber": 249, 23:02:03 INFO - "slavename": "talos-linux64-ix-021", 23:02:03 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 23:02:03 INFO - "platform": "ubuntu64_hw", 23:02:03 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 23:02:03 INFO - "branch": "fx-team", 23:02:03 INFO - "repo_path": "integration/fx-team", 23:02:03 INFO - "revision": "d8fa10d0a52eb52ee1fc46f88c37662c71af37a6", 23:02:03 INFO - "stage_platform": "linux64-pgo", 23:02:03 INFO - "builduid": "7dd269baf9164a7e8e03346a5c8413c8", 23:02:03 INFO - "slavebuilddir": "test" 23:02:03 INFO - } 23:02:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 23:02:03 INFO - Running post-action listener: _resource_record_post_action 23:02:03 INFO - ##### 23:02:03 INFO - ##### Finished read-buildbot-config step (success) 23:02:03 INFO - ##### 23:02:03 INFO - ##### 23:02:03 INFO - ##### Running download-and-extract step. 23:02:03 INFO - ##### 23:02:03 INFO - Running pre-action listener: _resource_record_pre_action 23:02:03 INFO - Running main action method: download_and_extract 23:02:03 INFO - mkdir: /builds/slave/test/build/tests 23:02:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:03 INFO - https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 23:02:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:02:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:02:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:02:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 23:02:04 INFO - Downloaded 1361 bytes. 23:02:04 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 23:02:04 INFO - Using the following test package requirements: 23:02:04 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:02:04 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 23:02:04 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 23:02:04 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'jsshell-linux-x86_64.zip'], 23:02:04 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 23:02:04 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 23:02:04 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 23:02:04 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 23:02:04 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 23:02:04 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 23:02:04 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 23:02:04 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 23:02:04 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:04 INFO - https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:02:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:02:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:02:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:02:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:02:07 INFO - Downloaded 24300608 bytes. 23:02:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:02:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:02:08 INFO - Return code: 0 23:02:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 23:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:08 INFO - https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 23:02:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:02:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:02:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 23:02:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 23:02:17 INFO - Downloaded 24300608 bytes. 23:02:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:02:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 23:02:18 INFO - Return code: 0 23:02:18 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:18 INFO - https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 23:02:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:02:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:02:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 23:02:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 23:02:19 INFO - Downloaded 14403333 bytes. 23:02:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:02:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 23:02:19 INFO - Return code: 0 23:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:19 INFO - https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 23:02:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:02:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:02:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:02:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 23:02:21 INFO - Downloaded 60337068 bytes. 23:02:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:02:21 INFO - mkdir: /builds/slave/test/properties 23:02:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:02:21 INFO - Writing to file /builds/slave/test/properties/build_url 23:02:21 INFO - Contents: 23:02:21 INFO - build_url:https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 23:02:21 INFO - Running post-action listener: _resource_record_post_action 23:02:21 INFO - Running post-action listener: set_extra_try_arguments 23:02:21 INFO - ##### 23:02:21 INFO - ##### Finished download-and-extract step (success) 23:02:21 INFO - ##### 23:02:21 INFO - ##### 23:02:21 INFO - ##### Running populate-webroot step. 23:02:21 INFO - ##### 23:02:21 INFO - Running pre-action listener: _resource_record_pre_action 23:02:21 INFO - Running main action method: populate_webroot 23:02:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:02:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:02:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:02:21 INFO - '--tppagecycles', 23:02:21 INFO - '7'], 23:02:21 INFO - 'tests': ['tsvgm']}}, 23:02:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:02:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:02:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:02:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:02:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:02:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:02:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['damp', 'tps']}, 23:02:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'tests': ['damp', 'tps']}, 23:02:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['dromaeo_dom']}, 23:02:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:02:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['basic_compositor_video']}, 23:02:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 23:02:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['a11yr', 23:02:21 INFO - 'ts_paint', 23:02:21 INFO - 'tpaint', 23:02:21 INFO - 'sessionrestore', 23:02:21 INFO - 'sessionrestore_no_auto_restore', 23:02:21 INFO - 'tabpaint']}, 23:02:21 INFO - 'other-e10s': {'tests': ['a11yr', 23:02:21 INFO - 'ts_paint', 23:02:21 INFO - 'tpaint', 23:02:21 INFO - 'sessionrestore', 23:02:21 INFO - 'sessionrestore_no_auto_restore', 23:02:21 INFO - 'tabpaint']}, 23:02:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:02:21 INFO - 'ts_paint', 23:02:21 INFO - 'tpaint', 23:02:21 INFO - 'sessionrestore', 23:02:21 INFO - 'sessionrestore_no_auto_restore', 23:02:21 INFO - 'tabpaint']}, 23:02:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:02:21 INFO - 'ts_paint', 23:02:21 INFO - 'tpaint', 23:02:21 INFO - 'sessionrestore', 23:02:21 INFO - 'sessionrestore_no_auto_restore', 23:02:21 INFO - 'tabpaint']}, 23:02:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['a11yr', 23:02:21 INFO - 'ts_paint', 23:02:21 INFO - 'tpaint', 23:02:21 INFO - 'sessionrestore', 23:02:21 INFO - 'sessionrestore_no_auto_restore', 23:02:21 INFO - 'tabpaint']}, 23:02:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['a11yr', 23:02:21 INFO - 'ts_paint', 23:02:21 INFO - 'tpaint', 23:02:21 INFO - 'sessionrestore', 23:02:21 INFO - 'sessionrestore_no_auto_restore', 23:02:21 INFO - 'tabpaint']}, 23:02:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['tsvgx', 23:02:21 INFO - 'tsvgr_opacity', 23:02:21 INFO - 'tart', 23:02:21 INFO - 'tscrollx', 23:02:21 INFO - 'cart']}, 23:02:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:02:21 INFO - 'tsvgr_opacity', 23:02:21 INFO - 'tart', 23:02:21 INFO - 'tscrollx', 23:02:21 INFO - 'cart']}, 23:02:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'talos_options': ['--disable-e10s'], 23:02:21 INFO - 'tests': ['tp5o']}, 23:02:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'tests': ['tp5o']}, 23:02:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'talos_options': ['--disable-e10s', 23:02:21 INFO - '--xperf_path', 23:02:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:02:21 INFO - 'tests': ['tp5n']}, 23:02:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:02:21 INFO - 'talos_options': ['--xperf_path', 23:02:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:02:21 INFO - 'tests': ['tp5n']}}, 23:02:21 INFO - 'talos.zip': {'path': '', 23:02:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:02:21 INFO - Running post-action listener: _resource_record_post_action 23:02:21 INFO - ##### 23:02:21 INFO - ##### Finished populate-webroot step (success) 23:02:21 INFO - ##### 23:02:21 INFO - ##### 23:02:21 INFO - ##### Running create-virtualenv step. 23:02:21 INFO - ##### 23:02:21 INFO - Running pre-action listener: _resource_record_pre_action 23:02:21 INFO - Running main action method: create_virtualenv 23:02:21 INFO - Creating virtualenv /builds/slave/test/build/venv 23:02:21 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 23:02:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:02:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:02:22 INFO - Using real prefix '/usr' 23:02:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:02:22 INFO - Installing distribute.............................................................................................................................................................................................done. 23:02:24 INFO - Installing pip.................done. 23:02:24 INFO - Return code: 0 23:02:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02: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', 'pip>=1.5'] in /builds/slave/test/build 23:02: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 pip>=1.5 23:02:24 INFO - Using env: {'DISPLAY': ':0', 23:02:24 INFO - 'HOME': '/home/cltbld', 23:02:24 INFO - 'LANG': 'en_US.UTF-8', 23:02:24 INFO - 'LANGUAGE': 'en_US:en', 23:02:24 INFO - 'LOGNAME': 'cltbld', 23:02:24 INFO - 'MAIL': '/var/mail/cltbld', 23:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:24 INFO - 'MOZ_NO_REMOTE': '1', 23:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:24 INFO - 'NO_EM_RESTART': '1', 23:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:24 INFO - 'PWD': '/builds/slave/test', 23:02:24 INFO - 'SHELL': '/bin/bash', 23:02:24 INFO - 'SHLVL': '1', 23:02:24 INFO - 'TERM': 'linux', 23:02:24 INFO - 'TMOUT': '86400', 23:02:24 INFO - 'USER': 'cltbld', 23:02:24 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:24 INFO - '_': '/tools/buildbot/bin/python'} 23:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:24 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 23:02:24 INFO - Cleaning up... 23:02:24 INFO - Return code: 0 23:02:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02: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', '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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 23:02: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 psutil>=3.1.1 23:02:24 INFO - Using env: {'DISPLAY': ':0', 23:02:24 INFO - 'HOME': '/home/cltbld', 23:02:24 INFO - 'LANG': 'en_US.UTF-8', 23:02:24 INFO - 'LANGUAGE': 'en_US:en', 23:02:24 INFO - 'LOGNAME': 'cltbld', 23:02:24 INFO - 'MAIL': '/var/mail/cltbld', 23:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:24 INFO - 'MOZ_NO_REMOTE': '1', 23:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:24 INFO - 'NO_EM_RESTART': '1', 23:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:24 INFO - 'PWD': '/builds/slave/test', 23:02:24 INFO - 'SHELL': '/bin/bash', 23:02:24 INFO - 'SHLVL': '1', 23:02:24 INFO - 'TERM': 'linux', 23:02:24 INFO - 'TMOUT': '86400', 23:02:24 INFO - 'USER': 'cltbld', 23:02:24 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:24 INFO - '_': '/tools/buildbot/bin/python'} 23:02:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:25 INFO - Downloading/unpacking psutil>=3.1.1 23:02: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 23:02: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 23:02: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 23:02: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 23:02: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 23:02: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 23:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:02:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:02:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:02:27 INFO - Installing collected packages: psutil 23:02:27 INFO - Running setup.py install for psutil 23:02:27 INFO - building 'psutil._psutil_linux' extension 23:02:27 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 23:02:27 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 23:02:27 INFO - building 'psutil._psutil_posix' extension 23:02:27 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 23:02:27 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 23:02:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:02:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:02:27 INFO - Successfully installed psutil 23:02:27 INFO - Cleaning up... 23:02:27 INFO - Return code: 0 23:02:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:27 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:27 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:27 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 23:02:27 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 23:02:27 INFO - Using env: {'DISPLAY': ':0', 23:02:27 INFO - 'HOME': '/home/cltbld', 23:02:27 INFO - 'LANG': 'en_US.UTF-8', 23:02:27 INFO - 'LANGUAGE': 'en_US:en', 23:02:27 INFO - 'LOGNAME': 'cltbld', 23:02:27 INFO - 'MAIL': '/var/mail/cltbld', 23:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:27 INFO - 'MOZ_NO_REMOTE': '1', 23:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:27 INFO - 'NO_EM_RESTART': '1', 23:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:27 INFO - 'PWD': '/builds/slave/test', 23:02:27 INFO - 'SHELL': '/bin/bash', 23:02:27 INFO - 'SHLVL': '1', 23:02:27 INFO - 'TERM': 'linux', 23:02:27 INFO - 'TMOUT': '86400', 23:02:27 INFO - 'USER': 'cltbld', 23:02:27 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:27 INFO - '_': '/tools/buildbot/bin/python'} 23:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:02: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 23:02: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 23:02: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 23:02: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 23:02: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 23:02: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 23:02:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:02:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:02:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:02:29 INFO - Installing collected packages: mozsystemmonitor 23:02:29 INFO - Running setup.py install for mozsystemmonitor 23:02:29 INFO - Successfully installed mozsystemmonitor 23:02:29 INFO - Cleaning up... 23:02:30 INFO - Return code: 0 23:02:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:02:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:30 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:30 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 23:02:30 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 23:02:30 INFO - Using env: {'DISPLAY': ':0', 23:02:30 INFO - 'HOME': '/home/cltbld', 23:02:30 INFO - 'LANG': 'en_US.UTF-8', 23:02:30 INFO - 'LANGUAGE': 'en_US:en', 23:02:30 INFO - 'LOGNAME': 'cltbld', 23:02:30 INFO - 'MAIL': '/var/mail/cltbld', 23:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:30 INFO - 'MOZ_NO_REMOTE': '1', 23:02:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:30 INFO - 'NO_EM_RESTART': '1', 23:02:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:30 INFO - 'PWD': '/builds/slave/test', 23:02:30 INFO - 'SHELL': '/bin/bash', 23:02:30 INFO - 'SHLVL': '1', 23:02:30 INFO - 'TERM': 'linux', 23:02:30 INFO - 'TMOUT': '86400', 23:02:30 INFO - 'USER': 'cltbld', 23:02:30 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:30 INFO - '_': '/tools/buildbot/bin/python'} 23:02:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:30 INFO - Downloading/unpacking jsonschema==2.5.1 23:02:30 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 23:02:30 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 23:02:30 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 23:02:30 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 23:02:30 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 23:02:30 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 23:02:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:02:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 INFO - Downloading functools32-3.2.3-2.tar.gz 23:02:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:02:32 INFO - warning: no files found matching '*.txt' 23:02:32 INFO - no previously-included directories found matching 'build' 23:02:32 INFO - no previously-included directories found matching 'dist' 23:02:32 INFO - no previously-included directories found matching '.git*' 23:02:32 INFO - Installing collected packages: jsonschema, functools32 23:02:32 INFO - Running setup.py install for functools32 23:02:32 INFO - warning: no files found matching '*.txt' 23:02:32 INFO - no previously-included directories found matching 'build' 23:02:32 INFO - no previously-included directories found matching 'dist' 23:02:32 INFO - no previously-included directories found matching '.git*' 23:02:32 INFO - Successfully installed jsonschema functools32 23:02:32 INFO - Cleaning up... 23:02:32 INFO - Return code: 0 23:02:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:02:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:32 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:32 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:32 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 23:02:32 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 23:02:32 INFO - Using env: {'DISPLAY': ':0', 23:02:32 INFO - 'HOME': '/home/cltbld', 23:02:32 INFO - 'LANG': 'en_US.UTF-8', 23:02:32 INFO - 'LANGUAGE': 'en_US:en', 23:02:32 INFO - 'LOGNAME': 'cltbld', 23:02:32 INFO - 'MAIL': '/var/mail/cltbld', 23:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:32 INFO - 'MOZ_NO_REMOTE': '1', 23:02:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:32 INFO - 'NO_EM_RESTART': '1', 23:02:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:32 INFO - 'PWD': '/builds/slave/test', 23:02:32 INFO - 'SHELL': '/bin/bash', 23:02:32 INFO - 'SHLVL': '1', 23:02:32 INFO - 'TERM': 'linux', 23:02:32 INFO - 'TMOUT': '86400', 23:02:32 INFO - 'USER': 'cltbld', 23:02:32 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:32 INFO - '_': '/tools/buildbot/bin/python'} 23:02:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:32 INFO - Downloading/unpacking blobuploader==1.2.4 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 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 23:02:32 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 23:02:34 INFO - Downloading blobuploader-1.2.4.tar.gz 23:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:02:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:02:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:02:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:02:35 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 23:02:35 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 23:02:35 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 23:02:35 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 23:02:35 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 23:02:35 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 23:02:35 INFO - Downloading docopt-0.6.1.tar.gz 23:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:02:35 INFO - Installing collected packages: blobuploader, requests, docopt 23:02:35 INFO - Running setup.py install for blobuploader 23:02:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:02:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:02:35 INFO - Running setup.py install for requests 23:02:35 INFO - Running setup.py install for docopt 23:02:35 INFO - Successfully installed blobuploader requests docopt 23:02:35 INFO - Cleaning up... 23:02:36 INFO - Return code: 0 23:02:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:36 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:36 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 23:02:36 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 23:02:36 INFO - Using env: {'DISPLAY': ':0', 23:02:36 INFO - 'HOME': '/home/cltbld', 23:02:36 INFO - 'LANG': 'en_US.UTF-8', 23:02:36 INFO - 'LANGUAGE': 'en_US:en', 23:02:36 INFO - 'LOGNAME': 'cltbld', 23:02:36 INFO - 'MAIL': '/var/mail/cltbld', 23:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:36 INFO - 'MOZ_NO_REMOTE': '1', 23:02:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:36 INFO - 'NO_EM_RESTART': '1', 23:02:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:36 INFO - 'PWD': '/builds/slave/test', 23:02:36 INFO - 'SHELL': '/bin/bash', 23:02:36 INFO - 'SHLVL': '1', 23:02:36 INFO - 'TERM': 'linux', 23:02:36 INFO - 'TMOUT': '86400', 23:02:36 INFO - 'USER': 'cltbld', 23:02:36 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:36 INFO - '_': '/tools/buildbot/bin/python'} 23:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:02:36 INFO - Running setup.py (path:/tmp/pip-NLuzDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:02:36 INFO - Running setup.py (path:/tmp/pip-zSGbZJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:02:36 INFO - Running setup.py (path:/tmp/pip-acm7rX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:02:36 INFO - Running setup.py (path:/tmp/pip-eXvyWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:02:36 INFO - Running setup.py (path:/tmp/pip-OnEJMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:02:36 INFO - Running setup.py (path:/tmp/pip-o1vu6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:02:36 INFO - Running setup.py (path:/tmp/pip-Je_2UF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:02:36 INFO - Running setup.py (path:/tmp/pip-E4s5No-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:02:36 INFO - Running setup.py (path:/tmp/pip-OVFfRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:02:36 INFO - Running setup.py (path:/tmp/pip-P1RyFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:02:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:02:36 INFO - Running setup.py (path:/tmp/pip-Jf8uLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:02:37 INFO - Running setup.py (path:/tmp/pip-_plcJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:02:37 INFO - Running setup.py (path:/tmp/pip-NqUpAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:02:37 INFO - Running setup.py (path:/tmp/pip-nup1Rf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:37 INFO - Running setup.py (path:/tmp/pip-E_AgJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:02:37 INFO - Running setup.py (path:/tmp/pip-pToEJK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:02:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:02:37 INFO - Running setup.py (path:/tmp/pip-W3L5K6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:02:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:02:37 INFO - Running setup.py install for manifestparser 23:02:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:02:37 INFO - Running setup.py install for mozcrash 23:02:37 INFO - Running setup.py install for mozdebug 23:02:37 INFO - Running setup.py install for mozdevice 23:02:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:02:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:02:37 INFO - Running setup.py install for mozfile 23:02:37 INFO - Running setup.py install for mozhttpd 23:02:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for mozinfo 23:02:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for mozInstall 23:02:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for mozleak 23:02:38 INFO - Running setup.py install for mozlog 23:02:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for moznetwork 23:02:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for mozprocess 23:02:38 INFO - Running setup.py install for mozprofile 23:02:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for mozrunner 23:02:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:02:38 INFO - Running setup.py install for mozscreenshot 23:02:39 INFO - Running setup.py install for moztest 23:02:39 INFO - Running setup.py install for mozversion 23:02:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:02:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:02:39 INFO - Cleaning up... 23:02:39 INFO - Return code: 0 23:02:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:39 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:39 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:39 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 23:02:39 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 23:02:39 INFO - Using env: {'DISPLAY': ':0', 23:02:39 INFO - 'HOME': '/home/cltbld', 23:02:39 INFO - 'LANG': 'en_US.UTF-8', 23:02:39 INFO - 'LANGUAGE': 'en_US:en', 23:02:39 INFO - 'LOGNAME': 'cltbld', 23:02:39 INFO - 'MAIL': '/var/mail/cltbld', 23:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:39 INFO - 'MOZ_NO_REMOTE': '1', 23:02:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:39 INFO - 'NO_EM_RESTART': '1', 23:02:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:39 INFO - 'PWD': '/builds/slave/test', 23:02:39 INFO - 'SHELL': '/bin/bash', 23:02:39 INFO - 'SHLVL': '1', 23:02:39 INFO - 'TERM': 'linux', 23:02:39 INFO - 'TMOUT': '86400', 23:02:39 INFO - 'USER': 'cltbld', 23:02:39 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:39 INFO - '_': '/tools/buildbot/bin/python'} 23:02:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:02:39 INFO - Running setup.py (path:/tmp/pip-QEXsRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:02:39 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)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:02:39 INFO - Running setup.py (path:/tmp/pip-GFcmkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:02:39 INFO - Running setup.py (path:/tmp/pip-xKSAlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:02:39 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)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:02:39 INFO - Running setup.py (path:/tmp/pip-6C6PcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:02:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:02:39 INFO - Running setup.py (path:/tmp/pip-Sue3wX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:02:39 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)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:02:39 INFO - Running setup.py (path:/tmp/pip-jzjxc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:02:39 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)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:02:39 INFO - Running setup.py (path:/tmp/pip-H3yyN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:02:39 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)) 23:02:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:02:39 INFO - Running setup.py (path:/tmp/pip-pHYbQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:02:40 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:02:40 INFO - Running setup.py (path:/tmp/pip-qDQ6N7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:02:40 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:02:40 INFO - Running setup.py (path:/tmp/pip-oevE5I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:02:40 INFO - Running setup.py (path:/tmp/pip-riMRFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:02:40 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:02:40 INFO - Running setup.py (path:/tmp/pip-7jd6c8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:02:40 INFO - Running setup.py (path:/tmp/pip-eHsdIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:02:40 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:02:40 INFO - Running setup.py (path:/tmp/pip-8oiCE9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:40 INFO - Running setup.py (path:/tmp/pip-rLbzh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:02:40 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:02:40 INFO - Running setup.py (path:/tmp/pip-ehq0ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:02:40 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)) 23:02:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:02:40 INFO - Running setup.py (path:/tmp/pip-Wlcuzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:02:40 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)) 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 23:02:40 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)) 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:02:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:02:40 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 23:02:40 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 23:02:40 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 23:02:40 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 23:02:40 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 23:02:40 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 23:02:42 INFO - Downloading blessings-1.6.tar.gz 23:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:02:42 INFO - Installing collected packages: blessings 23:02:42 INFO - Running setup.py install for blessings 23:02:42 INFO - Successfully installed blessings 23:02:42 INFO - Cleaning up... 23:02:42 INFO - Return code: 0 23:02:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:02:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:02:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:02:43 INFO - Reading from file tmpfile_stdout 23:02:43 INFO - Current package versions: 23:02:43 INFO - argparse == 1.2.1 23:02:43 INFO - blessings == 1.6 23:02:43 INFO - blobuploader == 1.2.4 23:02:43 INFO - docopt == 0.6.1 23:02:43 INFO - functools32 == 3.2.3-2 23:02:43 INFO - jsonschema == 2.5.1 23:02:43 INFO - manifestparser == 1.1 23:02:43 INFO - mozInstall == 1.12 23:02:43 INFO - mozcrash == 0.17 23:02:43 INFO - mozdebug == 0.1 23:02:43 INFO - mozdevice == 0.48 23:02:43 INFO - mozfile == 1.2 23:02:43 INFO - mozhttpd == 0.7 23:02:43 INFO - mozinfo == 0.9 23:02:43 INFO - mozleak == 0.1 23:02:43 INFO - mozlog == 3.2 23:02:43 INFO - moznetwork == 0.27 23:02:43 INFO - mozprocess == 0.22 23:02:43 INFO - mozprofile == 0.28 23:02:43 INFO - mozrunner == 6.11 23:02:43 INFO - mozscreenshot == 0.1 23:02:43 INFO - mozsystemmonitor == 0.3 23:02:43 INFO - moztest == 0.7 23:02:43 INFO - mozversion == 1.4 23:02:43 INFO - psutil == 3.1.1 23:02:43 INFO - requests == 1.2.3 23:02:43 INFO - wsgiref == 0.1.2 23:02:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:02:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:43 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:43 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 23:02:43 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 23:02:43 INFO - Using env: {'DISPLAY': ':0', 23:02:43 INFO - 'HOME': '/home/cltbld', 23:02:43 INFO - 'LANG': 'en_US.UTF-8', 23:02:43 INFO - 'LANGUAGE': 'en_US:en', 23:02:43 INFO - 'LOGNAME': 'cltbld', 23:02:43 INFO - 'MAIL': '/var/mail/cltbld', 23:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:43 INFO - 'MOZ_NO_REMOTE': '1', 23:02:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:43 INFO - 'NO_EM_RESTART': '1', 23:02:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:43 INFO - 'PWD': '/builds/slave/test', 23:02:43 INFO - 'SHELL': '/bin/bash', 23:02:43 INFO - 'SHLVL': '1', 23:02:43 INFO - 'TERM': 'linux', 23:02:43 INFO - 'TMOUT': '86400', 23:02:43 INFO - 'USER': 'cltbld', 23:02:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:43 INFO - '_': '/tools/buildbot/bin/python'} 23:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 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)) 23:02:43 INFO - Cleaning up... 23:02:43 INFO - Return code: 0 23:02:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:02:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:02:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:43 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:02:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:02:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:02:43 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 0xed5030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1196180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, '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': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', '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 23:02:43 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 23:02:43 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 23:02:43 INFO - Using env: {'DISPLAY': ':0', 23:02:43 INFO - 'HOME': '/home/cltbld', 23:02:43 INFO - 'LANG': 'en_US.UTF-8', 23:02:43 INFO - 'LANGUAGE': 'en_US:en', 23:02:43 INFO - 'LOGNAME': 'cltbld', 23:02:43 INFO - 'MAIL': '/var/mail/cltbld', 23:02:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:02:43 INFO - 'MOZ_NO_REMOTE': '1', 23:02:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:02:43 INFO - 'NO_EM_RESTART': '1', 23:02:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:02:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:02:43 INFO - 'PWD': '/builds/slave/test', 23:02:43 INFO - 'SHELL': '/bin/bash', 23:02:43 INFO - 'SHLVL': '1', 23:02:43 INFO - 'TERM': 'linux', 23:02:43 INFO - 'TMOUT': '86400', 23:02:43 INFO - 'USER': 'cltbld', 23:02:43 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:02:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:02:43 INFO - '_': '/tools/buildbot/bin/python'} 23:02:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:02:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:02:43 INFO - Cleaning up... 23:02:43 INFO - Return code: 0 23:02:43 INFO - Running post-action listener: _resource_record_post_action 23:02:43 INFO - Running post-action listener: _start_resource_monitoring 23:02:43 INFO - Starting resource monitoring. 23:02:43 INFO - ##### 23:02:43 INFO - ##### Finished create-virtualenv step (success) 23:02:43 INFO - ##### 23:02:43 INFO - ##### 23:02:43 INFO - ##### Running install step. 23:02:43 INFO - ##### 23:02:43 INFO - Running pre-action listener: _resource_record_pre_action 23:02:43 INFO - Running main action method: install 23:02:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:02:43 INFO - Reading from file tmpfile_stdout 23:02:43 INFO - Detecting whether we're running mozinstall >=1.0... 23:02:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:02:43 INFO - Reading from file tmpfile_stdout 23:02:43 INFO - Output received: 23:02:43 INFO - Usage: mozinstall [options] installer 23:02:43 INFO - Options: 23:02:43 INFO - -h, --help show this help message and exit 23:02:43 INFO - -d DEST, --destination=DEST 23:02:43 INFO - Directory to install application into. [default: 23:02:43 INFO - "/builds/slave/test"] 23:02:43 INFO - --app=APP Application being installed. [default: firefox] 23:02:43 INFO - mkdir: /builds/slave/test/build/application 23:02:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 23:02:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:03:02 INFO - Reading from file tmpfile_stdout 23:03:02 INFO - Output received: 23:03:02 INFO - /builds/slave/test/build/application/firefox/firefox 23:03:02 INFO - Running post-action listener: _resource_record_post_action 23:03:02 INFO - ##### 23:03:02 INFO - ##### Finished install step (success) 23:03:02 INFO - ##### 23:03:02 INFO - ##### 23:03:02 INFO - ##### Running run-tests step. 23:03:02 INFO - ##### 23:03:02 INFO - Running pre-action listener: _resource_record_pre_action 23:03:02 INFO - Running main action method: run_tests 23:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:03:02 INFO - Python 2.7.3 23:03:02 INFO - Return code: 0 23:03:02 INFO - grabbing minidump binary from tooltool 23:03:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:03:02 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 0x11943b0>, '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 0x11952b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1195740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:03:02 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 23:03:02 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 23:03:02 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 23:03:02 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:03:02 INFO - Return code: 0 23:03:02 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:03:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:03:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:03:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:03:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 23:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-021 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 23:03:02 INFO - Using env: {'DISPLAY': ':0', 23:03:02 INFO - 'HOME': '/home/cltbld', 23:03:02 INFO - 'LANG': 'en_US.UTF-8', 23:03:02 INFO - 'LANGUAGE': 'en_US:en', 23:03:02 INFO - 'LOGNAME': 'cltbld', 23:03:02 INFO - 'MAIL': '/var/mail/cltbld', 23:03:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:03:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:03:02 INFO - 'MOZ_NO_REMOTE': '1', 23:03:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:03:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:03:02 INFO - 'NO_EM_RESTART': '1', 23:03:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:03:02 INFO - 'PWD': '/builds/slave/test', 23:03:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:03:02 INFO - 'SHELL': '/bin/bash', 23:03:02 INFO - 'SHLVL': '1', 23:03:02 INFO - 'TERM': 'linux', 23:03:02 INFO - 'TMOUT': '86400', 23:03:02 INFO - 'USER': 'cltbld', 23:03:02 INFO - 'XDG_SESSION_COOKIE': 'df17445c3590ff7897617a0e00000225-1467783915.276653-605315000', 23:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:03:02 INFO - '_': '/tools/buildbot/bin/python'} 23:03:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-021', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 23:03:03 INFO - mozversion application_buildid: 20160705203035 23:03:03 INFO - mozversion application_changeset: d8fa10d0a52eb52ee1fc46f88c37662c71af37a6 23:03:03 INFO - mozversion application_display_name: Nightly 23:03:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:03:03 INFO - mozversion application_name: Firefox 23:03:03 INFO - mozversion application_remotingname: firefox 23:03:03 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:03:03 INFO - mozversion application_vendor: Mozilla 23:03:03 INFO - mozversion application_version: 50.0a1 23:03:03 INFO - mozversion platform_buildid: 20160705203035 23:03:03 INFO - mozversion platform_changeset: d8fa10d0a52eb52ee1fc46f88c37662c71af37a6 23:03:03 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:03:03 INFO - mozversion platform_version: 50.0a1 23:03:03 INFO - using testdate: 1467784983 23:03:03 INFO - actual date: 1467784983 23:03:03 INFO - starting webserver on 'localhost:56813' 23:03:03 INFO - SUITE-START | Running 2 tests 23:03:03 INFO - TEST-START | dromaeo_css 23:03:03 INFO - operating with platform_type : linux_ 23:03:03 INFO - Initialising browser for dromaeo_css test... 23:03:03 INFO - TEST-INFO | started process 21372 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJLYfPB/profile http://localhost:56813/getInfo.html) 23:03:08 INFO - PROCESS | 21372 | __metrics Screen width/height:1600/1200 23:03:08 INFO - PROCESS | 21372 | colorDepth:24 23:03:08 INFO - PROCESS | 21372 | Browser inner width/height: 1024/697 23:03:08 INFO - PROCESS | 21372 | __metrics 23:03:09 INFO - PROCESS | 21372 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:03:10 INFO - TEST-INFO | 21372: exit 0 23:03:10 INFO - Browser initialized. 23:03:10 INFO - Running cycle 1/1 for dromaeo_css test... 23:03:10 INFO - TEST-INFO | started process 21454 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJLYfPB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:03:22 INFO - PROCESS | 21454 | RSS: Main: 157626368 23:03:22 INFO - PROCESS | 21454 | 23:04:13 INFO - PROCESS | 21454 | 1467785053371 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 23:06:22 INFO - PROCESS | 21454 | Cycle 1(1): loaded http://localhost:56813/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56813/tests/dromaeo/cssquery-ext.html) 23:06:23 INFO - PROCESS | 21454 | RSS: Main: 242913280 23:06:23 INFO - PROCESS | 21454 | 23:09:25 INFO - PROCESS | 21454 | Cycle 1(1): loaded http://localhost:56813/tests/dromaeo/cssquery-ext.html (next: http://localhost:56813/tests/dromaeo/cssquery-jquery.html) 23:09:26 INFO - PROCESS | 21454 | RSS: Main: 240115712 23:09:26 INFO - PROCESS | 21454 | 23:12:28 INFO - PROCESS | 21454 | Cycle 1(1): loaded http://localhost:56813/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56813/tests/dromaeo/cssquery-mootools.html) 23:12:28 INFO - PROCESS | 21454 | RSS: Main: 242692096 23:12:28 INFO - PROCESS | 21454 | 23:15:30 INFO - PROCESS | 21454 | Cycle 1(1): loaded http://localhost:56813/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56813/tests/dromaeo/cssquery-prototype.html) 23:15:31 INFO - PROCESS | 21454 | RSS: Main: 240349184 23:15:31 INFO - PROCESS | 21454 | 23:18:34 INFO - PROCESS | 21454 | Cycle 1(1): loaded http://localhost:56813/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56813/tests/dromaeo/cssquery-yui.html) 23:18:34 INFO - PROCESS | 21454 | RSS: Main: 225562624 23:18:34 INFO - PROCESS | 21454 | 23:21:37 INFO - PROCESS | 21454 | Cycle 1(1): loaded http://localhost:56813/tests/dromaeo/cssquery-yui.html (next: http://localhost:56813/tests/dromaeo/cssquery-dojo.html) 23:21:38 INFO - PROCESS | 21454 | RSS: Main: 311214080 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | __start_tp_report 23:21:38 INFO - PROCESS | 21454 | _x_x_mozilla_page_load 23:21:38 INFO - PROCESS | 21454 | _x_x_mozilla_page_load_details 23:21:38 INFO - PROCESS | 21454 | |i|pagename|runs| 23:21:38 INFO - PROCESS | 21454 | |0;dojo.html;3111.751059915207;3143.0727935259088;3144.0724986129094;3151.574537437445;3182.1249156481963;5309.840704778858;5314.973425132878;5358.490943360384;5423.2136340230645;5454.10007348787;5341.973290133554;5361.7587208575615;5386.165144402614;5389.137737961927;5426.158945363465;4808.495108013661;4850.951490485085;4877.634177436688;4890.902181956359;5064.873378165538;4960.156773348522;4965.677230979974;5001.199808030716;5018.172001619729;5022.9246561301425;4757.762111894409;4802.951970480303;4812.855614331575;4854.49027852074;4875.07373599015;4817.494163112858;4820.638452116105;4829.492903245144;4892.535209155125;4930.852074437773;4852.830150944736;4854.781534830941;4885.951140488585;4893.8776530586665;4924.80300787968;5207.4792520748;5302.681839089667;5365.651232669864;5387.057264978613;5387.380451248078;5791.449812267828;5794.362620111784;5841.503472204868;5841.88316233673;5850.619709718897;5867.442592953663;5888.705564721789;5892.440218179266;5899.0266606009955;5904.822855314347;5226.947730522722;5364.195370694427;5384.542313903323;5399.730013499348;5474;5978.521718262528;6004.729787159588;6005.669688167105;6013.939860601338;6015.278166619946;5513.393526712059;5766.826995190151;5774.68239246837;5778.248827652463;5780.306363236355;5687.488126068673;5717.828465146053;5751.223584815996;5847.06446968483;5857.619254748428;5749.913751293734;5754.856128596735;5758.452946970031;5758.913616295759;5768.451997060272;5614.157876318585;5667.4332566743;5713.314402271754;5754.913676294859;5766.88466230673;4411.51473337939;4413;4425.380446737459;4448.421705178306;4450.109978004412;3935.35066713989;3943.7042221833476;3943.8028098594955;3960.2673505401594;3984.1234928315725;5343.519083282523;5401.6218864679095;5433.8369848904595;5475.260839786578;5478.808241711521;5697.202391665171;5719.685417302088;5737.254156959569;5746.856328591735;5763.942360576424;5451.291332126798;5460.044492213926;5461.0716178249795;5463.781448742086;5467.0705979983495;4872.4640289568115;4963.5780958618925;4986.850394488171;4993.1262029144755;5025.673331233458;5978.701064946736;6034.63792172471;6040.09398590215;6055.757769689163;6108.908366374508;5822.621529600562;5832.183494310715;5832.795852145155;5843.853903652443;5844.444777746107;5802.883942321215;5924.7926322578505;5968.641881487125;6033.54748393881;6050.788222412194;5669.20631111653;5671.971640141773;5684.090545512699;5703.08750599902;5743.770249189985;5745.741441635053;5762.135679648086;5806.9419305806405;5812.593118481665;5815.5929084963645;5473.2063850742215;5621.100623900156;5632.85917852057;5653.095504719226;5673.574481913791;5684.77260909559;5691.402402747817;5697.800576979952;5710.942890571041;5716.342620598518;5620.213170156263;5632.9436705632415;5637.126245431965;5653.152027195789;5656.8020119295625;5517.36550296721;5636.436356364331;5687.0616348302065;5693.971530142323;5737.512311453574;5590.440955904377;5611.354694210218;5617.859553511195;5620.522255608319;5627.8874422512135 23:21:38 INFO - PROCESS | 21454 | |1;ext.html;579.4379451931625;605.927288725353;616.6515918506041;618.2858798088208;631.7946667333118;29866.402671946547;32229.19427014326;32312;32439.51340729891;35485.64514354856;43216.351754723706;43605.34591981115;43648.56351436485;43658;43756.78121609396;8576.0995095515;8711.651533938635;8723.520206388646;8725.912740872573;8726.52004139772;8911.598978045971;8987.101289871025;8999.550022498848;9005.099490051009;9010.819783604324;8993.190612844843;9027.187553120219;9045.547722613876;9048.819023619524;9048.819023619524;8968.192862642361;8976.730698079067;9015.36892417531;9020.639174433014;9024.18782309592;8899.555022248862;8952.507612081334;8956.417832840845;8966.686165984225;8969.551522423919;41098.38352424716;41354.58645413537;41387;41511.37732934008;41531.58468415307;3115.469058901825;3235.692609202122;3316.7346612270962;3327;3435.055359776057;30092.548611770842;32676.509852352232;33297;33364;33418.164545886306;4325.718828276152;4373.212821692094;4391.407160033386;4482.394876691637;4495.168393847149;371657.1417142924;372967;373503;374765;377621;33918;34434;34599;59654.701726491956;60671.393286067454;35742.821285893406;35824;35871.28257434889;35987;35990.64009359925;13845.100068495516;13876.722465550632;13905.95705322105;13906.235157066458;13913;12462.688432789253;12516.249025058525;12524.248545087325;12527.248365098123;12534.498620055278;8262.958685206535;8264.586770661443;8270.503769773833;8328.08391076981;8344.499330040095;3197.8720851165695;3208.181913612014;3258.25060236147;3261.8532166052582;3270.362279355503;990.559201155479;1040.7138037039874;1061.851340812287;1118.9720256993478;1125.9324440533594;31814;32541.51187732186;32665;32871.34257314888;33098;977.6040703515088;1061.6125114333213;1087.983680244797;1093.5079214353573;1128.1257025804903;16882.240299186567;17099.316027358767;17143.57141071483;17226.913865430844;17587.472375828747;587374;594595;597436;599546;602004;57821;58203;58496;58556.70721646365;58668.7066564673;1177.9941100294443;1198.9269603704636;1326.8805807477374;1369.698666293414;1380.758367285721;32195.35609287848;33422.66577334293;33452.330953381286;33878.3224335507;34052.467638956805;50226.74886625543;50556.49443505518;50791;50825.74587127041;50829.49170508396;409999;410148;410293;410586;410845;24277.271681849572;26831;27604.723952761018;27670.16989490238;28001.579976300778;47442.52557474381;48146;48684;49684;49812.25281620706;40969;41902.580974191085;42183;42225.78887105545;42416.787916060224;40986.79506602448;41417.79291103525;41867.371989419575;42189.78905105578;42468;41092;41583.58416415918;42201.577984219766;42310.7884460588;42378.15243695171;35068.64931350654;36304.455433168;36552.268954620224;37204.813975929945;37242.62757372392;24560.631590525798;24765.245898361998;24971;25002.3749406259;25084.49831003406 23:21:38 INFO - PROCESS | 21454 | |2;jquery.html;384.79990404989417;386.5766985151258;394.0582008998494;401.43798681845396;410.97123201375916;18447.354342598013;20626;20653.17387304506;21134.99999999998;21165.259215927446;18703.25186992522;19260.518487037833;19537.51156221095;19617.215311387565;19787.604247915035;5564.10974244119;5688.2605261315975;5798.623089499169;5814.7092645367775;5903.4686878180955;5743.626664266831;5784.537237021028;5792.68140252286;5801.47786699197;5821.738021789009;5585.273914391144;5720.141978703186;5766.135079738031;5779.479846813786;5780.248567686195;5666.065099258617;5698.51562617178;5717.514011309044;5729.656220626775;5764.308283006024;4983.302337672729;5291.629585928987;5293.550048245904;5353.384360798519;5363;22693.54612907749;22753.431164220863;23027.078916843308;23197.304080877602;23217.419564510852;2149.6990421341025;2293.2317673579373;2475.8143139264625;2601.140293856401;3039.0427015490272;9756.317057805956;9795.412275263507;9863;10179.440130792804;10311.48442577868;1908.742319786825;1980.4949737816908;1985.811347921945;1989.204318272688;1990.6516359637005;82541.80869460915;86176.56911715464;87923.56038219832;88059.99999999936;89481;10534;10653.414062226651;10936.343819370704;11252.437378131224;11324.433778311051;15474.845251547566;15608.453704120302;15620.062796232263;15638.29627666735;15645.530634080997;8076.8384632307025;8364.163583641588;8390.916090839135;8405.95797021011;8413.70552030676;7977.521348719096;8056.919430805734;8057.717979870677;8176.6320515576945;8242.835143297101;4807.110684523374;4818.229083346648;4851.927221091686;4913.9017219656125;4982.153033984231;1931.6619591571418;1983.9008049597462;1988.9403317900485;2002.879827210372;2037.7350944377433;867.991320086791;938.3619138985418;959.52023988006;985.7634167799677;1007.415698894654;18940.526486837938;19217;19945.401637950883;20195.192192312436;20242;924.6902287733684;942.8585712143233;945.3004776465503;970.6990832841842;1005.2510879394897;10529.36823790575;10554.683359499073;10559.683209503728;10609.310394824312;10632.362058276374;358028;360288;360535;483029;484492;98159;104251;104721;104907;104986.47506762417;998.565623953568;999.9900000999897;1000.2498126405196;1007.3502590828874;1012.099231683802;18194.545136371697;18207.90896045511;18353;18433.90783046076;18543;40554.59445405626;40877.38683919803;44660.77669611761;46327.76836115798;46909.53090469052;64096.67951660212;64482.677586613645;64946.35053649592;65003;65911.67044164748;8612.31101511865;8690.391672582859;8871.245944094824;8875.289976801969;9005.099490050943;10094.343867648578;13463.596092117252;13676.65808354799;14117.929410352883;14526;11181.440927953407;11397.826943310205;12049.939750301193;12380.752384952431;12611.747765044463;11341.546338146381;11384.260023098472;11598.47806848677;11743.236689615147;11765.11761617865;11545;12124.09069319787;12124.27254364741;12423.881850633486;12517.311547864954;8555.761230867161;8704.434211776213;8842.115788421106;8926.375153739176;8994.280457563511;8604.569771511524;9604;11960.162788604714;12087.456064477296;12473.438695258554 23:21:38 INFO - PROCESS | 21454 | |3;mootools.html;1294.3981048812311;1446.6889618731973;1459.2120255062266;1589.3801417447198;1755.6839768841603;8213.096559378471;8648.308135349173;8649.264812490937;8886.77783055424;8911.68809091682;8537.743867683965;8755;8755.212030917217;8758;8771.956140219307;2230.297456301264;2279.4757205842584;2280.851744636593;2284.7829456201634;2289.347535952254;2265.9886700566476;2266.580682573721;2273.6362182050875;2274.6815445837515;2331.9417014574688;2290.656401539774;2313.8611683298973;2319.0375993962484;2336.9532609347802;2340.707411573553;2256.8758718270487;2260.4009937366527;2261.875596842173;2265.422317309084;2266.036934302923;2218.279059305716;2220.8667479951255;2223.6998005269406;2227.13141874669;2227.253869953551;8234.05308389531;8425.199606037417;8441.240288374076;8788.340874434443;8833;5113.130767769489;5220.060389129955;5224.712640804754;5225.085610018232;5232.215167724872;3229.022513875874;3289.753268504872;3297.0438572813855;3326.6673332666783;3343.866245350183;1170.2451918512597;1188.0851744157037;1202.6632542888005;1206.203905422417;1212.2181193130382;12316.568920087753;12370.072244581692;12372.566960156351;12435.564755233614;12447.813282800766;4158.625723684883;4277.6791740618955;4345.217860785026;4417.249067658506;4433.534478879736;9623.56693948774;9710.514474276259;9720.61117555304;9741.220702343795;9763.072508111718;4991.251312303184;4995.575376093;4998.2502624606595;5009.574186194214;5029.421616514148;4136.648384887319;4563.361129441881;4683.063387322549;4737.549932756383;4751.6436267279405;3710.6289371062676;3716.888493345204;3784.981075094662;3821.6178382162116;3827.0432391902023;1047.2302857399804;1083.9918875445753;1089.0198821061108;1089.8801131875487;1092.0444610965405;738.4756822655975;840.970566030186;854.2567965869733;861.5304658960881;863.8531449653574;5772.855678608068;5863.472287494146;5924.792632257937;5943.732532036069;6079.513638908877;916.6837441082815;971.8930917599058;1071.9517621707041;1074.8172810622214;1106.4080716816466;4154.273002224659;4200.159968006411;4298;4337.110892267077;4369.3227549729845;10665.84001239982;11171.720706982227;11173.441327933733;11190.272632279039;11258.605948791754;10545;10906.127509799197;11045.723856903642;11052.613158539574;11075.280106793032;8161;8230.958845205736;8267.421280510427;8276.420650554403;8294.37792165578;6104.298005729221;6329.145565348619;6339.587926784929;6376.872462550816;6438.227412710505;10327.070563649308;10417.42704151279;10434.269601127848;10442.634507792494;10448.738781530523;9853.211743060681;10108.444035578113;10172.491375431347;10186.337888037255;10187.7453063674;7414.073240844895;7905.604719763874;7947.364210863001;8076.353891688534;8079.757607271791;7857.882131767914;8058.597070146587;8093.179034717142;8145.755627331189;8147.185281471805;7157;7292.0520332356455;7414.777556673308;7528.736494222676;7666.655000525101;7194.424446044409;7337.119545654342;7368;7405.2224516425;7465.7013719450615;6850.314968503109;6855.5886646801355;6893.103896493424;6894.586324820527;6950;4984.800607975641;5099.791539911646;5170.922436163536;5210.6613070150315;5215.4002289735645;5249.343832020997;5262.736863156903;5272.604554658339;5424.403315635198;5431.538319242909 23:21:38 INFO - PROCESS | 21454 | |4;prototype.html;313.7098184179634;322.43090944482987;323.63106059092655;325.4207510631076;326.82514854552807;5317.78728850846;5359.928014397116;5372.435894231108;5388.43421440749;5404.000259951912;5272.288241087452;5310.256564081032;5370.3287089113865;5373;5380.677159370431;4005.539362973259;4040.4949381327333;4189.685773566979;4207.978960105195;4208.516020657617;4146.917061658765;4155.812988415529;4177.603127702879;4189.97905010476;4191.706580539363;4020.8540565938715;4119.608637179464;4120.773357465353;4149.97925010376;4160.126373461577;4089.141280331134;4175.979120104411;4181.205570941542;4200.51694055183;4203.831846726127;3701.9259614807825;3716.7769933804057;3718.2935242304147;3723.1809002019513;3724.42271447926;5186.740662966837;5194.947995541777;5202.271681964528;5211.7133557654315;5231.110711179109;4545.318202269653;4774.785134668913;4805.135075686375;4835.5889749371345;4841.661083724141;5573.554115670777;5603.383627800979;5620.325560932673;5650.632708873951;5678.545716342724;4041.8787436376956;4055.4322394864744;4061.5735347788354;4063.8984025399304;4064.471618689581;5603.439656034364;5782.306123265235;5784;5792.4497172768515;5797.739101740432;4049.392591111357;4085.7946905662902;4093.97953010239;4096.549379568245;4100.2414553307735;4132.524759652679;4158.313878210122;4167.2290626233635;4183.937240941388;4184.937225941613;4489.989752305771;4710.62315014798;4722.386089808303;4737.549932756383;4777.331173635694;5641.248569701185;5645.802396916089;5673.234113394721;5678.545716342683;5685.488306052475;1408.0284603623468;1408.9084209526347;1416.9291535423188;1424.7649137892338;1433.1616004637403;905.7237542549453;922.9017469211617;926.3885835348772;955.8709574207393;991.1921783746224;5362.731863406814;5393.892122157535;5400;5414.83755487336;5427.538659214011;5642.915356269659;5656.943430565724;5667.688277144714;5677.290338707661;5681.4034526374135;5218.191180367049;5380.515753582196;5390.434004429479;5468.125099983983;5550.472705093091;3037.7873548851812;3043.6347638283546;3064.3564851381016;3064.417760625474;3072.2780146665514;5761.654300741969;5947.8401711666675;5950.761969521257;5955.553333500005;5963.5825492216;5895.145203945404;5903.3801450847905;5907.940920590739;5910.586258961917;5924.496417804448;5932.677700487654;5937.139114828412;5956.880862382728;6005.549583781321;6020.157177995085;5645.633033852787;5768.307803063659;5803.65178089316;5804.738786754522;5814.796482123105;5625.240592520121;5638.351589567253;5678.630888992202;5687.971560142173;5708;5722.628029178257;5939.7921072763975;5961.552883533839;5969.641821490724;5975.193348898017;4102.589741025873;4124.628783409507;4128;4136.131412403429;4136.586341365839;5492.203630473638;5606.579506537108;5803;5809.709514524341;5822.82531524055;5596.720163991866;5603.7758489659955;5604;5611.410801865745;5617.157426385911;5553.222548843246;5562.471565201299;5564.527015203591;5585.385607583244;5588.546977785723;5235.214717792362;5538.861528461821;5543.972280138573;5548.528375088002;5559.833205003856;5608.747606357642;5636.2954630671165;5641.576881733804;5656.858578535405;5704.600677952503;5292.259083728204;5565.165225216088;5580.21876937221;5598.412166722435;5611.3266408031295 23:21:38 INFO - PROCESS | 21454 | |5;yui.html;740.5741698523348;746.1717493582124;775.08539922891;791.9247671471209;822.3174764945096;14390.49633262836;15097.094174349519;15165.31756070978;15280.235988200588;16252.51242462728;14765.77851332231;14867.182304973223;15059.849401505982;15159.090454572734;15179.16514591697;649.2339039932901;758.1319389299276;777.7355699062332;798.2935102434359;798.8482188384218;794.0352471746827;806.8144326804838;807.2250639386167;807.4105902691039;808.1958451340895;839.1734141870284;840.7099550655045;841.3227351981652;842.3008902610818;843.9915600843974;674.3930462583689;792.0970094092739;813.2802469814197;829.85062688716;829.9087100418949;597.3250227243187;597.614538622586;597.9103134529812;597.9581429299951;598.0341748076868;14487;14492.130472171704;14531.200783956878;14537.92731036338;14572.12567245958;14638;14871.397505436506;14878.404863805436;14904.701905961929;14925.328360223708;11816.645500634995;11922.284662920165;11937.34344611046;12247.816282755766;12562.623121306284;4546.680114640652;4610.884727881797;4668.603168730628;4670.346151538787;4721.787519561628;79403;80527.19472805255;80854.59572702099;81134;81360.9999999994;11312;11601.825972610417;11618.88381116195;11897.226680265756;12425.565105221274;21975.340739777832;22012;22019.339419817432;22030.11879524801;22037.228696995528;8809.911900881038;8932.642694292155;9400.811983760423;9404.24766018717;9411.529423528797;4872.561469467694;4945.332380128709;4954.430240522386;5078.644494885322;5085.084684756779;3411.26657768576;3414.5219669246326;3416.6924976751716;3494.353544594258;3555.9111022224133;598.6049207523014;599.3616798109999;599.66718471248;600.5465873265656;602.8553147244718;636.3445650979498;658.8155316511385;662.9171353580803;676.6650507998572;740.1155619035201;8926.464412135292;8965.661516392202;9289.674861379819;9539.379940303857;9646.807063858823;627.6171535363425;638.7061951502268;650.9682153786207;696.8780463418882;705.2383425900118;4259.87220383389;4265.723145424172;4329.6536277098385;4541.182587134347;4662.300654901792;69750.65124674344;69824.3017569828;70600;83088.58455707804;118119.67178708054;99395.07259128951;102884;102934;104269;104287.4785626067;52799.208011879855;73063.63468182625;85089.57455212684;87151;90364.09635903557;7991.120976692443;7997.400194985283;8060.959695201486;8221.588920553828;8225.95887020561;53531.46468535265;53985.19022214592;54994.450055498935;55701.44298556963;56337.71831140818;52058;73919;74607.25392746003;75635;76017.6199119023;11309.208355415041;11349.262297950607;11422.657320280405;11735.354555499189;12126.757464850478;12236.327002014974;12248.571300004813;12443.688907777378;12759.681007974503;13030.804537932128;11613.187076904536;11743.823842642196;12024.879751202727;12153.392330383269;12530.310832904277;11567.438395816456;11701.180917335705;11798.233114847852;12141.271523708605;12200.694982625506;10487.897050341026;10500.947495262475;10538.104261137583;11441.713957151138;11721.824172637587;9602.231821454126;9723.56243969009;9759;9908.752281193028;10158.492075396052;9192.632294708137;9197.816043679222;9341.813163736551;9373.281886940144;9397.671081512115 23:21:38 INFO - PROCESS | 21454 | __end_tp_report 23:21:38 INFO - PROCESS | 21454 | __start_cc_report 23:21:38 INFO - PROCESS | 21454 | _x_x_mozilla_cycle_collect,1015 23:21:38 INFO - PROCESS | 21454 | __end_cc_report 23:21:38 INFO - PROCESS | 21454 | __startTimestamp1467786098113__endTimestamp 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | ------- Summary: start ------- 23:21:38 INFO - PROCESS | 21454 | Number of tests: 6 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | [#0] dojo.html Cycles:165 Average:5370.80 Median:5620.21 stddev:616.95 (11.0%) stddev-sans-first:592.84 23:21:38 INFO - PROCESS | 21454 | Values: 3111.8 3143.1 3144.1 3151.6 3182.1 5309.8 5315.0 5358.5 5423.2 5454.1 5342.0 5361.8 5386.2 5389.1 5426.2 4808.5 4851.0 4877.6 4890.9 5064.9 4960.2 4965.7 5001.2 5018.2 5022.9 4757.8 4803.0 4812.9 4854.5 4875.1 4817.5 4820.6 4829.5 4892.5 4930.9 4852.8 4854.8 4886.0 4893.9 4924.8 5207.5 5302.7 5365.7 5387.1 5387.4 5791.4 5794.4 5841.5 5841.9 5850.6 5867.4 5888.7 5892.4 5899.0 5904.8 5226.9 5364.2 5384.5 5399.7 5474.0 5978.5 6004.7 6005.7 6013.9 6015.3 5513.4 5766.8 5774.7 5778.2 5780.3 5687.5 5717.8 5751.2 5847.1 5857.6 5749.9 5754.9 5758.5 5758.9 5768.5 5614.2 5667.4 5713.3 5754.9 5766.9 4411.5 4413.0 4425.4 4448.4 4450.1 3935.4 3943.7 3943.8 3960.3 3984.1 5343.5 5401.6 5433.8 5475.3 5478.8 5697.2 5719.7 5737.3 5746.9 5763.9 5451.3 5460.0 5461.1 5463.8 5467.1 4872.5 4963.6 4986.9 4993.1 5025.7 5978.7 6034.6 6040.1 6055.8 6108.9 5822.6 5832.2 5832.8 5843.9 5844.4 5802.9 5924.8 5968.6 6033.5 6050.8 5669.2 5672.0 5684.1 5703.1 5743.8 5745.7 5762.1 5806.9 5812.6 5815.6 5473.2 5621.1 5632.9 5653.1 5673.6 5684.8 5691.4 5697.8 5710.9 5716.3 5620.2 5632.9 5637.1 5653.2 5656.8 5517.4 5636.4 5687.1 5694.0 5737.5 5590.4 5611.4 5617.9 5620.5 5627.9 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | [#1] ext.html Cycles:180 Average:60009.17 Median:410439.50 stddev:125402.93 (30.6%) stddev-sans-first:125675.34 23:21:38 INFO - PROCESS | 21454 | Values: 579.4 605.9 616.7 618.3 631.8 29866.4 32229.2 32312.0 32439.5 35485.6 43216.4 43605.3 43648.6 43658.0 43756.8 8576.1 8711.7 8723.5 8725.9 8726.5 8911.6 8987.1 8999.6 9005.1 9010.8 8993.2 9027.2 9045.5 9048.8 9048.8 8968.2 8976.7 9015.4 9020.6 9024.2 8899.6 8952.5 8956.4 8966.7 8969.6 41098.4 41354.6 41387.0 41511.4 41531.6 3115.5 3235.7 3316.7 3327.0 3435.1 30092.5 32676.5 33297.0 33364.0 33418.2 4325.7 4373.2 4391.4 4482.4 4495.2 371657.1 372967.0 373503.0 374765.0 377621.0 33918.0 34434.0 34599.0 59654.7 60671.4 35742.8 35824.0 35871.3 35987.0 35990.6 13845.1 13876.7 13906.0 13906.2 13913.0 12462.7 12516.2 12524.2 12527.2 12534.5 8263.0 8264.6 8270.5 8328.1 8344.5 3197.9 3208.2 3258.3 3261.9 3270.4 990.6 1040.7 1061.9 1119.0 1125.9 31814.0 32541.5 32665.0 32871.3 33098.0 977.6 1061.6 1088.0 1093.5 1128.1 16882.2 17099.3 17143.6 17226.9 17587.5 587374.0 594595.0 597436.0 599546.0 602004.0 57821.0 58203.0 58496.0 58556.7 58668.7 1178.0 1198.9 1326.9 1369.7 1380.8 32195.4 33422.7 33452.3 33878.3 34052.5 50226.7 50556.5 50791.0 50825.7 50829.5 409999.0 410148.0 410293.0 410586.0 410845.0 24277.3 26831.0 27604.7 27670.2 28001.6 47442.5 48146.0 48684.0 49684.0 49812.3 40969.0 41902.6 42183.0 42225.8 42416.8 40986.8 41417.8 41867.4 42189.8 42468.0 41092.0 41583.6 42201.6 42310.8 42378.2 35068.6 36304.5 36552.3 37204.8 37242.6 24560.6 24765.2 24971.0 25002.4 25084.5 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | [#2] jquery.html Cycles:180 Average:27580.23 Median:360411.50 stddev:69323.08 (19.2%) stddev-sans-first:69487.47 23:21:38 INFO - PROCESS | 21454 | Values: 384.8 386.6 394.1 401.4 411.0 18447.4 20626.0 20653.2 21135.0 21165.3 18703.3 19260.5 19537.5 19617.2 19787.6 5564.1 5688.3 5798.6 5814.7 5903.5 5743.6 5784.5 5792.7 5801.5 5821.7 5585.3 5720.1 5766.1 5779.5 5780.2 5666.1 5698.5 5717.5 5729.7 5764.3 4983.3 5291.6 5293.6 5353.4 5363.0 22693.5 22753.4 23027.1 23197.3 23217.4 2149.7 2293.2 2475.8 2601.1 3039.0 9756.3 9795.4 9863.0 10179.4 10311.5 1908.7 1980.5 1985.8 1989.2 1990.7 82541.8 86176.6 87923.6 88060.0 89481.0 10534.0 10653.4 10936.3 11252.4 11324.4 15474.8 15608.5 15620.1 15638.3 15645.5 8076.8 8364.2 8390.9 8406.0 8413.7 7977.5 8056.9 8057.7 8176.6 8242.8 4807.1 4818.2 4851.9 4913.9 4982.2 1931.7 1983.9 1988.9 2002.9 2037.7 868.0 938.4 959.5 985.8 1007.4 18940.5 19217.0 19945.4 20195.2 20242.0 924.7 942.9 945.3 970.7 1005.3 10529.4 10554.7 10559.7 10609.3 10632.4 358028.0 360288.0 360535.0 483029.0 484492.0 98159.0 104251.0 104721.0 104907.0 104986.5 998.6 1000.0 1000.2 1007.4 1012.1 18194.5 18207.9 18353.0 18433.9 18543.0 40554.6 40877.4 44660.8 46327.8 46909.5 64096.7 64482.7 64946.4 65003.0 65911.7 8612.3 8690.4 8871.2 8875.3 9005.1 10094.3 13463.6 13676.7 14117.9 14526.0 11181.4 11397.8 12049.9 12380.8 12611.7 11341.5 11384.3 11598.5 11743.2 11765.1 11545.0 12124.1 12124.3 12423.9 12517.3 8555.8 8704.4 8842.1 8926.4 8994.3 8604.6 9604.0 11960.2 12087.5 12473.4 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | [#3] mootools.html Cycles:180 Average:5737.68 Median:4744.60 stddev:3295.80 (69.5%) stddev-sans-first:3288.13 23:21:38 INFO - PROCESS | 21454 | Values: 1294.4 1446.7 1459.2 1589.4 1755.7 8213.1 8648.3 8649.3 8886.8 8911.7 8537.7 8755.0 8755.2 8758.0 8772.0 2230.3 2279.5 2280.9 2284.8 2289.3 2266.0 2266.6 2273.6 2274.7 2331.9 2290.7 2313.9 2319.0 2337.0 2340.7 2256.9 2260.4 2261.9 2265.4 2266.0 2218.3 2220.9 2223.7 2227.1 2227.3 8234.1 8425.2 8441.2 8788.3 8833.0 5113.1 5220.1 5224.7 5225.1 5232.2 3229.0 3289.8 3297.0 3326.7 3343.9 1170.2 1188.1 1202.7 1206.2 1212.2 12316.6 12370.1 12372.6 12435.6 12447.8 4158.6 4277.7 4345.2 4417.2 4433.5 9623.6 9710.5 9720.6 9741.2 9763.1 4991.3 4995.6 4998.3 5009.6 5029.4 4136.6 4563.4 4683.1 4737.5 4751.6 3710.6 3716.9 3785.0 3821.6 3827.0 1047.2 1084.0 1089.0 1089.9 1092.0 738.5 841.0 854.3 861.5 863.9 5772.9 5863.5 5924.8 5943.7 6079.5 916.7 971.9 1072.0 1074.8 1106.4 4154.3 4200.2 4298.0 4337.1 4369.3 10665.8 11171.7 11173.4 11190.3 11258.6 10545.0 10906.1 11045.7 11052.6 11075.3 8161.0 8231.0 8267.4 8276.4 8294.4 6104.3 6329.1 6339.6 6376.9 6438.2 10327.1 10417.4 10434.3 10442.6 10448.7 9853.2 10108.4 10172.5 10186.3 10187.7 7414.1 7905.6 7947.4 8076.4 8079.8 7857.9 8058.6 8093.2 8145.8 8147.2 7157.0 7292.1 7414.8 7528.7 7666.7 7194.4 7337.1 7368.0 7405.2 7465.7 6850.3 6855.6 6893.1 6894.6 6950.0 4984.8 5099.8 5170.9 5210.7 5215.4 5249.3 5262.7 5272.6 5424.4 5431.5 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | [#4] prototype.html Cycles:180 Average:4723.32 Median:5396.95 stddev:1391.37 (25.8%) stddev-sans-first:1355.34 23:21:38 INFO - PROCESS | 21454 | Values: 313.7 322.4 323.6 325.4 326.8 5317.8 5359.9 5372.4 5388.4 5404.0 5272.3 5310.3 5370.3 5373.0 5380.7 4005.5 4040.5 4189.7 4208.0 4208.5 4146.9 4155.8 4177.6 4190.0 4191.7 4020.9 4119.6 4120.8 4150.0 4160.1 4089.1 4176.0 4181.2 4200.5 4203.8 3701.9 3716.8 3718.3 3723.2 3724.4 5186.7 5194.9 5202.3 5211.7 5231.1 4545.3 4774.8 4805.1 4835.6 4841.7 5573.6 5603.4 5620.3 5650.6 5678.5 4041.9 4055.4 4061.6 4063.9 4064.5 5603.4 5782.3 5784.0 5792.4 5797.7 4049.4 4085.8 4094.0 4096.5 4100.2 4132.5 4158.3 4167.2 4183.9 4184.9 4490.0 4710.6 4722.4 4737.5 4777.3 5641.2 5645.8 5673.2 5678.5 5685.5 1408.0 1408.9 1416.9 1424.8 1433.2 905.7 922.9 926.4 955.9 991.2 5362.7 5393.9 5400.0 5414.8 5427.5 5642.9 5656.9 5667.7 5677.3 5681.4 5218.2 5380.5 5390.4 5468.1 5550.5 3037.8 3043.6 3064.4 3064.4 3072.3 5761.7 5947.8 5950.8 5955.6 5963.6 5895.1 5903.4 5907.9 5910.6 5924.5 5932.7 5937.1 5956.9 6005.5 6020.2 5645.6 5768.3 5803.7 5804.7 5814.8 5625.2 5638.4 5678.6 5688.0 5708.0 5722.6 5939.8 5961.6 5969.6 5975.2 4102.6 4124.6 4128.0 4136.1 4136.6 5492.2 5606.6 5803.0 5809.7 5822.8 5596.7 5603.8 5604.0 5611.4 5617.2 5553.2 5562.5 5564.5 5585.4 5588.5 5235.2 5538.9 5544.0 5548.5 5559.8 5608.7 5636.3 5641.6 5656.9 5704.6 5292.3 5565.2 5580.2 5598.4 5611.3 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - PROCESS | 21454 | [#5] yui.html Cycles:180 Average:19524.91 Median:55347.95 stddev:27769.76 (50.2%) stddev-sans-first:27811.84 23:21:38 INFO - PROCESS | 21454 | Values: 740.6 746.2 775.1 791.9 822.3 14390.5 15097.1 15165.3 15280.2 16252.5 14765.8 14867.2 15059.8 15159.1 15179.2 649.2 758.1 777.7 798.3 798.8 794.0 806.8 807.2 807.4 808.2 839.2 840.7 841.3 842.3 844.0 674.4 792.1 813.3 829.9 829.9 597.3 597.6 597.9 598.0 598.0 14487.0 14492.1 14531.2 14537.9 14572.1 14638.0 14871.4 14878.4 14904.7 14925.3 11816.6 11922.3 11937.3 12247.8 12562.6 4546.7 4610.9 4668.6 4670.3 4721.8 79403.0 80527.2 80854.6 81134.0 81361.0 11312.0 11601.8 11618.9 11897.2 12425.6 21975.3 22012.0 22019.3 22030.1 22037.2 8809.9 8932.6 9400.8 9404.2 9411.5 4872.6 4945.3 4954.4 5078.6 5085.1 3411.3 3414.5 3416.7 3494.4 3555.9 598.6 599.4 599.7 600.5 602.9 636.3 658.8 662.9 676.7 740.1 8926.5 8965.7 9289.7 9539.4 9646.8 627.6 638.7 651.0 696.9 705.2 4259.9 4265.7 4329.7 4541.2 4662.3 69750.7 69824.3 70600.0 83088.6 118119.7 99395.1 102884.0 102934.0 104269.0 104287.5 52799.2 73063.6 85089.6 87151.0 90364.1 7991.1 7997.4 8061.0 8221.6 8226.0 53531.5 53985.2 54994.5 55701.4 56337.7 52058.0 73919.0 74607.3 75635.0 76017.6 11309.2 11349.3 11422.7 11735.4 12126.8 12236.3 12248.6 12443.7 12759.7 13030.8 11613.2 11743.8 12024.9 12153.4 12530.3 11567.4 11701.2 11798.2 12141.3 12200.7 10487.9 10500.9 10538.1 11441.7 11721.8 9602.2 9723.6 9759.0 9908.8 10158.5 9192.6 9197.8 9341.8 9373.3 9397.7 23:21:38 INFO - PROCESS | 21454 | -------- Summary: end -------- 23:21:38 INFO - PROCESS | 21454 | 23:21:38 INFO - TEST-INFO | 21454: exit 0 23:21:38 INFO - TEST-OK | dromaeo_css | took 1115796ms 23:21:38 INFO - TEST-START | kraken 23:21:38 INFO - operating with platform_type : linux_ 23:21:38 INFO - Initialising browser for kraken test... 23:21:38 INFO - TEST-INFO | started process 21648 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp91S5KH/profile http://localhost:56813/getInfo.html) 23:21:43 INFO - PROCESS | 21648 | __metrics Screen width/height:1600/1200 23:21:43 INFO - PROCESS | 21648 | colorDepth:24 23:21:43 INFO - PROCESS | 21648 | Browser inner width/height: 1024/697 23:21:43 INFO - PROCESS | 21648 | __metrics 23:21:45 INFO - PROCESS | 21648 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 23:21:46 INFO - TEST-INFO | 21648: exit 0 23:21:46 INFO - Browser initialized. 23:21:46 INFO - Running cycle 1/1 for kraken test... 23:21:46 INFO - TEST-INFO | started process 21734 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp91S5KH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:21:57 INFO - PROCESS | 21734 | RSS: Main: 156745728 23:21:57 INFO - PROCESS | 21734 | 23:22:00 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56813/tests/kraken/driver.html?testName=audio-beat-detection) 23:22:01 INFO - PROCESS | 21734 | RSS: Main: 215674880 23:22:01 INFO - PROCESS | 21734 | 23:22:03 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56813/tests/kraken/driver.html?testName=audio-dft) 23:22:04 INFO - PROCESS | 21734 | RSS: Main: 204234752 23:22:04 INFO - PROCESS | 21734 | 23:22:07 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56813/tests/kraken/driver.html?testName=audio-fft) 23:22:07 INFO - PROCESS | 21734 | RSS: Main: 269410304 23:22:07 INFO - PROCESS | 21734 | 23:22:09 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56813/tests/kraken/driver.html?testName=audio-oscillator) 23:22:10 INFO - PROCESS | 21734 | RSS: Main: 180682752 23:22:10 INFO - PROCESS | 21734 | 23:22:12 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56813/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:22:13 INFO - PROCESS | 21734 | RSS: Main: 184815616 23:22:13 INFO - PROCESS | 21734 | 23:22:16 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56813/tests/kraken/driver.html?testName=imaging-darkroom) 23:22:16 INFO - PROCESS | 21734 | RSS: Main: 220717056 23:22:16 INFO - PROCESS | 21734 | 23:22:20 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56813/tests/kraken/driver.html?testName=imaging-desaturate) 23:22:20 INFO - PROCESS | 21734 | RSS: Main: 221286400 23:22:20 INFO - PROCESS | 21734 | 23:22:23 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56813/tests/kraken/driver.html?testName=json-parse-financial) 23:22:24 INFO - PROCESS | 21734 | RSS: Main: 221413376 23:22:24 INFO - PROCESS | 21734 | 23:22:25 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56813/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:22:26 INFO - PROCESS | 21734 | RSS: Main: 209399808 23:22:26 INFO - PROCESS | 21734 | 23:22:28 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:22:28 INFO - PROCESS | 21734 | RSS: Main: 213274624 23:22:28 INFO - PROCESS | 21734 | 23:22:30 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:22:31 INFO - PROCESS | 21734 | RSS: Main: 210165760 23:22:31 INFO - PROCESS | 21734 | 23:22:33 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:22:34 INFO - PROCESS | 21734 | RSS: Main: 224329728 23:22:34 INFO - PROCESS | 21734 | 23:22:37 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:22:37 INFO - PROCESS | 21734 | RSS: Main: 224411648 23:22:37 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | Cycle 1(1): loaded http://localhost:56813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56813/tests/kraken/driver.html?testName=ai-astar) 23:22:39 INFO - PROCESS | 21734 | RSS: Main: 266657792 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | __start_tp_report 23:22:39 INFO - PROCESS | 21734 | _x_x_mozilla_page_load 23:22:39 INFO - PROCESS | 21734 | _x_x_mozilla_page_load_details 23:22:39 INFO - PROCESS | 21734 | |i|pagename|runs| 23:22:39 INFO - PROCESS | 21734 | |0;ai-astar;102;110;111;111;111;110;109;111;109;110 23:22:39 INFO - PROCESS | 21734 | |1;audio-beat-detection;145;115;118;175;117;118;138;115;147;117 23:22:39 INFO - PROCESS | 21734 | |2;audio-dft;163;160;160;160;161;159;160;159;161;161 23:22:39 INFO - PROCESS | 21734 | |3;audio-fft;74;74;75;127;75;75;75;74;111;75 23:22:39 INFO - PROCESS | 21734 | |4;audio-oscillator;85;84;118;83;83;84;123;84;84;130 23:22:39 INFO - PROCESS | 21734 | |5;imaging-gaussian-blur;116;117;116;116;116;116;117;116;116;117 23:22:39 INFO - PROCESS | 21734 | |6;imaging-darkroom;146;146;145;146;145;146;146;154;145;145 23:22:39 INFO - PROCESS | 21734 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;96 23:22:39 INFO - PROCESS | 21734 | |8;json-parse-financial;53;52;53;55;54;54;56;53;55;53 23:22:39 INFO - PROCESS | 21734 | |9;json-stringify-tinderbox;62;62;62;62;62;62;63;62;62;62 23:22:39 INFO - PROCESS | 21734 | |10;stanford-crypto-aes;76;76;75;76;78;75;75;75;75;78 23:22:39 INFO - PROCESS | 21734 | |11;stanford-crypto-ccm;118;129;128;119;123;122;128;120;163;119 23:22:39 INFO - PROCESS | 21734 | |12;stanford-crypto-pbkdf2;160;159;153;160;154;160;157;159;154;156 23:22:39 INFO - PROCESS | 21734 | |13;stanford-crypto-sha256-iterative;62;63;62;64;63;65;61;63;60;61 23:22:39 INFO - PROCESS | 21734 | __end_tp_report 23:22:39 INFO - PROCESS | 21734 | __start_cc_report 23:22:39 INFO - PROCESS | 21734 | _x_x_mozilla_cycle_collect,1665 23:22:39 INFO - PROCESS | 21734 | __end_cc_report 23:22:39 INFO - PROCESS | 21734 | __startTimestamp1467786159721__endTimestamp 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | ------- Summary: start ------- 23:22:39 INFO - PROCESS | 21734 | Number of tests: 14 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:2.72 (2.5%) stddev-sans-first:0.83 23:22:39 INFO - PROCESS | 21734 | Values: 102.0 110.0 111.0 111.0 111.0 110.0 109.0 111.0 109.0 110.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#1] audio-beat-detection Cycles:10 Average:130.50 Median:128.00 stddev:20.21 (15.8%) stddev-sans-first:20.75 23:22:39 INFO - PROCESS | 21734 | Values: 145.0 115.0 118.0 175.0 117.0 118.0 138.0 115.0 147.0 117.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:0.78 23:22:39 INFO - PROCESS | 21734 | Values: 163.0 160.0 160.0 160.0 161.0 159.0 160.0 159.0 161.0 161.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#3] audio-fft Cycles:10 Average:83.50 Median:75.00 stddev:19.09 (25.5%) stddev-sans-first:19.94 23:22:39 INFO - PROCESS | 21734 | Values: 74.0 74.0 75.0 127.0 75.0 75.0 75.0 74.0 111.0 75.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:84.00 stddev:19.45 (23.2%) stddev-sans-first:20.23 23:22:39 INFO - PROCESS | 21734 | Values: 85.0 84.0 118.0 83.0 83.0 84.0 123.0 84.0 84.0 130.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#5] imaging-gaussian-blur Cycles:10 Average:116.30 Median:116.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 23:22:39 INFO - PROCESS | 21734 | Values: 116.0 117.0 116.0 116.0 116.0 116.0 117.0 116.0 116.0 117.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.00 stddev:2.72 (1.9%) stddev-sans-first:2.88 23:22:39 INFO - PROCESS | 21734 | Values: 146.0 146.0 145.0 146.0 145.0 146.0 146.0 154.0 145.0 145.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 23:22:39 INFO - PROCESS | 21734 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.23 (2.3%) stddev-sans-first:1.27 23:22:39 INFO - PROCESS | 21734 | Values: 53.0 52.0 53.0 55.0 54.0 54.0 56.0 53.0 55.0 53.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:62.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 23:22:39 INFO - PROCESS | 21734 | Values: 62.0 62.0 62.0 62.0 62.0 62.0 63.0 62.0 62.0 62.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#10] stanford-crypto-aes Cycles:10 Average:75.90 Median:76.00 stddev:1.20 (1.6%) stddev-sans-first:1.27 23:22:39 INFO - PROCESS | 21734 | Values: 76.0 76.0 75.0 76.0 78.0 75.0 75.0 75.0 75.0 78.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#11] stanford-crypto-ccm Cycles:10 Average:126.90 Median:125.50 stddev:13.34 (10.6%) stddev-sans-first:13.75 23:22:39 INFO - PROCESS | 21734 | Values: 118.0 129.0 128.0 119.0 123.0 122.0 128.0 120.0 163.0 119.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:157.20 Median:159.00 stddev:2.78 (1.7%) stddev-sans-first:2.76 23:22:39 INFO - PROCESS | 21734 | Values: 160.0 159.0 153.0 160.0 154.0 160.0 157.0 159.0 154.0 156.0 23:22:39 INFO - PROCESS | 21734 | 23:22:39 INFO - PROCESS | 21734 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.40 Median:63.00 stddev:1.51 (2.4%) stddev-sans-first:1.59 23:22:39 INFO - PROCESS | 21734 | Values: 62.0 63.0 62.0 64.0 63.0 65.0 61.0 63.0 60.0 61.0 23:22:39 INFO - PROCESS | 21734 | -------- Summary: end -------- 23:22:39 INFO - PROCESS | 21734 | 23:22:40 INFO - TEST-INFO | 21734: exit 0 23:22:40 INFO - TEST-OK | kraken | took 61812ms 23:22:40 INFO - SUITE-END | took 1177s 23:22:40 INFO - Completed test suite (00:19:37) 23:22:40 INFO - javascript benchmark 23:22:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3111.751059915207, 3143.0727935259088, 3144.0724986129094, 3151.574537437445, 3182.1249156481963, 5309.840704778858, 5314.973425132878, 5358.490943360384, 5423.2136340230645, 5454.10007348787, 5341.973290133554, 5361.7587208575615, 5386.165144402614, 5389.137737961927, 5426.158945363465, 4808.495108013661, 4850.951490485085, 4877.634177436688, 4890.902181956359, 5064.873378165538, 4960.156773348522, 4965.677230979974, 5001.199808030716, 5018.172001619729, 5022.9246561301425, 4757.762111894409, 4802.951970480303, 4812.855614331575, 4854.49027852074, 4875.07373599015, 4817.494163112858, 4820.638452116105, 4829.492903245144, 4892.535209155125, 4930.852074437773, 4852.830150944736, 4854.781534830941, 4885.951140488585, 4893.8776530586665, 4924.80300787968, 5207.4792520748, 5302.681839089667, 5365.651232669864, 5387.057264978613, 5387.380451248078, 5791.449812267828, 5794.362620111784, 5841.503472204868, 5841.88316233673, 5850.619709718897, 5867.442592953663, 5888.705564721789, 5892.440218179266, 5899.0266606009955, 5904.822855314347, 5226.947730522722, 5364.195370694427, 5384.542313903323, 5399.730013499348, 5474.0, 5978.521718262528, 6004.729787159588, 6005.669688167105, 6013.939860601338, 6015.278166619946, 5513.393526712059, 5766.826995190151, 5774.68239246837, 5778.248827652463, 5780.306363236355, 5687.488126068673, 5717.828465146053, 5751.223584815996, 5847.06446968483, 5857.619254748428, 5749.913751293734, 5754.856128596735, 5758.452946970031, 5758.913616295759, 5768.451997060272, 5614.157876318585, 5667.4332566743, 5713.314402271754, 5754.913676294859, 5766.88466230673, 4411.51473337939, 4413.0, 4425.380446737459, 4448.421705178306, 4450.109978004412, 3935.35066713989, 3943.7042221833476, 3943.8028098594955, 3960.2673505401594, 3984.1234928315725, 5343.519083282523, 5401.6218864679095, 5433.8369848904595, 5475.260839786578, 5478.808241711521, 5697.202391665171, 5719.685417302088, 5737.254156959569, 5746.856328591735, 5763.942360576424, 5451.291332126798, 5460.044492213926, 5461.0716178249795, 5463.781448742086, 5467.0705979983495, 4872.4640289568115, 4963.5780958618925, 4986.850394488171, 4993.1262029144755, 5025.673331233458, 5978.701064946736, 6034.63792172471, 6040.09398590215, 6055.757769689163, 6108.908366374508, 5822.621529600562, 5832.183494310715, 5832.795852145155, 5843.853903652443, 5844.444777746107, 5802.883942321215, 5924.7926322578505, 5968.641881487125, 6033.54748393881, 6050.788222412194, 5669.20631111653, 5671.971640141773, 5684.090545512699, 5703.08750599902, 5743.770249189985, 5745.741441635053, 5762.135679648086, 5806.9419305806405, 5812.593118481665, 5815.5929084963645, 5473.2063850742215, 5621.100623900156, 5632.85917852057, 5653.095504719226, 5673.574481913791, 5684.77260909559, 5691.402402747817, 5697.800576979952, 5710.942890571041, 5716.342620598518, 5620.213170156263, 5632.9436705632415, 5637.126245431965, 5653.152027195789, 5656.8020119295625, 5517.36550296721, 5636.436356364331, 5687.0616348302065, 5693.971530142323, 5737.512311453574, 5590.440955904377, 5611.354694210218, 5617.859553511195, 5620.522255608319, 5627.8874422512135], "value": 5328.993848222581, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.4379451931625, 605.927288725353, 616.6515918506041, 618.2858798088208, 631.7946667333118, 29866.402671946547, 32229.19427014326, 32312.0, 32439.51340729891, 35485.64514354856, 43216.351754723706, 43605.34591981115, 43648.56351436485, 43658.0, 43756.78121609396, 8576.0995095515, 8711.651533938635, 8723.520206388646, 8725.912740872573, 8726.52004139772, 8911.598978045971, 8987.101289871025, 8999.550022498848, 9005.099490051009, 9010.819783604324, 8993.190612844843, 9027.187553120219, 9045.547722613876, 9048.819023619524, 9048.819023619524, 8968.192862642361, 8976.730698079067, 9015.36892417531, 9020.639174433014, 9024.18782309592, 8899.555022248862, 8952.507612081334, 8956.417832840845, 8966.686165984225, 8969.551522423919, 41098.38352424716, 41354.58645413537, 41387.0, 41511.37732934008, 41531.58468415307, 3115.469058901825, 3235.692609202122, 3316.7346612270962, 3327.0, 3435.055359776057, 30092.548611770842, 32676.509852352232, 33297.0, 33364.0, 33418.164545886306, 4325.718828276152, 4373.212821692094, 4391.407160033386, 4482.394876691637, 4495.168393847149, 371657.1417142924, 372967.0, 373503.0, 374765.0, 377621.0, 33918.0, 34434.0, 34599.0, 59654.701726491956, 60671.393286067454, 35742.821285893406, 35824.0, 35871.28257434889, 35987.0, 35990.64009359925, 13845.100068495516, 13876.722465550632, 13905.95705322105, 13906.235157066458, 13913.0, 12462.688432789253, 12516.249025058525, 12524.248545087325, 12527.248365098123, 12534.498620055278, 8262.958685206535, 8264.586770661443, 8270.503769773833, 8328.08391076981, 8344.499330040095, 3197.8720851165695, 3208.181913612014, 3258.25060236147, 3261.8532166052582, 3270.362279355503, 990.559201155479, 1040.7138037039874, 1061.851340812287, 1118.9720256993478, 1125.9324440533594, 31814.0, 32541.51187732186, 32665.0, 32871.34257314888, 33098.0, 977.6040703515088, 1061.6125114333213, 1087.983680244797, 1093.5079214353573, 1128.1257025804903, 16882.240299186567, 17099.316027358767, 17143.57141071483, 17226.913865430844, 17587.472375828747, 587374.0, 594595.0, 597436.0, 599546.0, 602004.0, 57821.0, 58203.0, 58496.0, 58556.70721646365, 58668.7066564673, 1177.9941100294443, 1198.9269603704636, 1326.8805807477374, 1369.698666293414, 1380.758367285721, 32195.35609287848, 33422.66577334293, 33452.330953381286, 33878.3224335507, 34052.467638956805, 50226.74886625543, 50556.49443505518, 50791.0, 50825.74587127041, 50829.49170508396, 409999.0, 410148.0, 410293.0, 410586.0, 410845.0, 24277.271681849572, 26831.0, 27604.723952761018, 27670.16989490238, 28001.579976300778, 47442.52557474381, 48146.0, 48684.0, 49684.0, 49812.25281620706, 40969.0, 41902.580974191085, 42183.0, 42225.78887105545, 42416.787916060224, 40986.79506602448, 41417.79291103525, 41867.371989419575, 42189.78905105578, 42468.0, 41092.0, 41583.58416415918, 42201.577984219766, 42310.7884460588, 42378.15243695171, 35068.64931350654, 36304.455433168, 36552.268954620224, 37204.813975929945, 37242.62757372392, 24560.631590525798, 24765.245898361998, 24971.0, 25002.3749406259, 25084.49831003406], "value": 18563.61103770838, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.79990404989417, 386.5766985151258, 394.0582008998494, 401.43798681845396, 410.97123201375916, 18447.354342598013, 20626.0, 20653.17387304506, 21134.99999999998, 21165.259215927446, 18703.25186992522, 19260.518487037833, 19537.51156221095, 19617.215311387565, 19787.604247915035, 5564.10974244119, 5688.2605261315975, 5798.623089499169, 5814.7092645367775, 5903.4686878180955, 5743.626664266831, 5784.537237021028, 5792.68140252286, 5801.47786699197, 5821.738021789009, 5585.273914391144, 5720.141978703186, 5766.135079738031, 5779.479846813786, 5780.248567686195, 5666.065099258617, 5698.51562617178, 5717.514011309044, 5729.656220626775, 5764.308283006024, 4983.302337672729, 5291.629585928987, 5293.550048245904, 5353.384360798519, 5363.0, 22693.54612907749, 22753.431164220863, 23027.078916843308, 23197.304080877602, 23217.419564510852, 2149.6990421341025, 2293.2317673579373, 2475.8143139264625, 2601.140293856401, 3039.0427015490272, 9756.317057805956, 9795.412275263507, 9863.0, 10179.440130792804, 10311.48442577868, 1908.742319786825, 1980.4949737816908, 1985.811347921945, 1989.204318272688, 1990.6516359637005, 82541.80869460915, 86176.56911715464, 87923.56038219832, 88059.99999999936, 89481.0, 10534.0, 10653.414062226651, 10936.343819370704, 11252.437378131224, 11324.433778311051, 15474.845251547566, 15608.453704120302, 15620.062796232263, 15638.29627666735, 15645.530634080997, 8076.8384632307025, 8364.163583641588, 8390.916090839135, 8405.95797021011, 8413.70552030676, 7977.521348719096, 8056.919430805734, 8057.717979870677, 8176.6320515576945, 8242.835143297101, 4807.110684523374, 4818.229083346648, 4851.927221091686, 4913.9017219656125, 4982.153033984231, 1931.6619591571418, 1983.9008049597462, 1988.9403317900485, 2002.879827210372, 2037.7350944377433, 867.991320086791, 938.3619138985418, 959.52023988006, 985.7634167799677, 1007.415698894654, 18940.526486837938, 19217.0, 19945.401637950883, 20195.192192312436, 20242.0, 924.6902287733684, 942.8585712143233, 945.3004776465503, 970.6990832841842, 1005.2510879394897, 10529.36823790575, 10554.683359499073, 10559.683209503728, 10609.310394824312, 10632.362058276374, 358028.0, 360288.0, 360535.0, 483029.0, 484492.0, 98159.0, 104251.0, 104721.0, 104907.0, 104986.47506762417, 998.565623953568, 999.9900000999897, 1000.2498126405196, 1007.3502590828874, 1012.099231683802, 18194.545136371697, 18207.90896045511, 18353.0, 18433.90783046076, 18543.0, 40554.59445405626, 40877.38683919803, 44660.77669611761, 46327.76836115798, 46909.53090469052, 64096.67951660212, 64482.677586613645, 64946.35053649592, 65003.0, 65911.67044164748, 8612.31101511865, 8690.391672582859, 8871.245944094824, 8875.289976801969, 9005.099490050943, 10094.343867648578, 13463.596092117252, 13676.65808354799, 14117.929410352883, 14526.0, 11181.440927953407, 11397.826943310205, 12049.939750301193, 12380.752384952431, 12611.747765044463, 11341.546338146381, 11384.260023098472, 11598.47806848677, 11743.236689615147, 11765.11761617865, 11545.0, 12124.09069319787, 12124.27254364741, 12423.881850633486, 12517.311547864954, 8555.761230867161, 8704.434211776213, 8842.115788421106, 8926.375153739176, 8994.280457563511, 8604.569771511524, 9604.0, 11960.162788604714, 12087.456064477296, 12473.438695258554], "value": 9317.332244145342, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.3981048812311, 1446.6889618731973, 1459.2120255062266, 1589.3801417447198, 1755.6839768841603, 8213.096559378471, 8648.308135349173, 8649.264812490937, 8886.77783055424, 8911.68809091682, 8537.743867683965, 8755.0, 8755.212030917217, 8758.0, 8771.956140219307, 2230.297456301264, 2279.4757205842584, 2280.851744636593, 2284.7829456201634, 2289.347535952254, 2265.9886700566476, 2266.580682573721, 2273.6362182050875, 2274.6815445837515, 2331.9417014574688, 2290.656401539774, 2313.8611683298973, 2319.0375993962484, 2336.9532609347802, 2340.707411573553, 2256.8758718270487, 2260.4009937366527, 2261.875596842173, 2265.422317309084, 2266.036934302923, 2218.279059305716, 2220.8667479951255, 2223.6998005269406, 2227.13141874669, 2227.253869953551, 8234.05308389531, 8425.199606037417, 8441.240288374076, 8788.340874434443, 8833.0, 5113.130767769489, 5220.060389129955, 5224.712640804754, 5225.085610018232, 5232.215167724872, 3229.022513875874, 3289.753268504872, 3297.0438572813855, 3326.6673332666783, 3343.866245350183, 1170.2451918512597, 1188.0851744157037, 1202.6632542888005, 1206.203905422417, 1212.2181193130382, 12316.568920087753, 12370.072244581692, 12372.566960156351, 12435.564755233614, 12447.813282800766, 4158.625723684883, 4277.6791740618955, 4345.217860785026, 4417.249067658506, 4433.534478879736, 9623.56693948774, 9710.514474276259, 9720.61117555304, 9741.220702343795, 9763.072508111718, 4991.251312303184, 4995.575376093, 4998.2502624606595, 5009.574186194214, 5029.421616514148, 4136.648384887319, 4563.361129441881, 4683.063387322549, 4737.549932756383, 4751.6436267279405, 3710.6289371062676, 3716.888493345204, 3784.981075094662, 3821.6178382162116, 3827.0432391902023, 1047.2302857399804, 1083.9918875445753, 1089.0198821061108, 1089.8801131875487, 1092.0444610965405, 738.4756822655975, 840.970566030186, 854.2567965869733, 861.5304658960881, 863.8531449653574, 5772.855678608068, 5863.472287494146, 5924.792632257937, 5943.732532036069, 6079.513638908877, 916.6837441082815, 971.8930917599058, 1071.9517621707041, 1074.8172810622214, 1106.4080716816466, 4154.273002224659, 4200.159968006411, 4298.0, 4337.110892267077, 4369.3227549729845, 10665.84001239982, 11171.720706982227, 11173.441327933733, 11190.272632279039, 11258.605948791754, 10545.0, 10906.127509799197, 11045.723856903642, 11052.613158539574, 11075.280106793032, 8161.0, 8230.958845205736, 8267.421280510427, 8276.420650554403, 8294.37792165578, 6104.298005729221, 6329.145565348619, 6339.587926784929, 6376.872462550816, 6438.227412710505, 10327.070563649308, 10417.42704151279, 10434.269601127848, 10442.634507792494, 10448.738781530523, 9853.211743060681, 10108.444035578113, 10172.491375431347, 10186.337888037255, 10187.7453063674, 7414.073240844895, 7905.604719763874, 7947.364210863001, 8076.353891688534, 8079.757607271791, 7857.882131767914, 8058.597070146587, 8093.179034717142, 8145.755627331189, 8147.185281471805, 7157.0, 7292.0520332356455, 7414.777556673308, 7528.736494222676, 7666.655000525101, 7194.424446044409, 7337.119545654342, 7368.0, 7405.2224516425, 7465.7013719450615, 6850.314968503109, 6855.5886646801355, 6893.103896493424, 6894.586324820527, 6950.0, 4984.800607975641, 5099.791539911646, 5170.922436163536, 5210.6613070150315, 5215.4002289735645, 5249.343832020997, 5262.736863156903, 5272.604554658339, 5424.403315635198, 5431.538319242909], "value": 4565.686907226982, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7098184179634, 322.43090944482987, 323.63106059092655, 325.4207510631076, 326.82514854552807, 5317.78728850846, 5359.928014397116, 5372.435894231108, 5388.43421440749, 5404.000259951912, 5272.288241087452, 5310.256564081032, 5370.3287089113865, 5373.0, 5380.677159370431, 4005.539362973259, 4040.4949381327333, 4189.685773566979, 4207.978960105195, 4208.516020657617, 4146.917061658765, 4155.812988415529, 4177.603127702879, 4189.97905010476, 4191.706580539363, 4020.8540565938715, 4119.608637179464, 4120.773357465353, 4149.97925010376, 4160.126373461577, 4089.141280331134, 4175.979120104411, 4181.205570941542, 4200.51694055183, 4203.831846726127, 3701.9259614807825, 3716.7769933804057, 3718.2935242304147, 3723.1809002019513, 3724.42271447926, 5186.740662966837, 5194.947995541777, 5202.271681964528, 5211.7133557654315, 5231.110711179109, 4545.318202269653, 4774.785134668913, 4805.135075686375, 4835.5889749371345, 4841.661083724141, 5573.554115670777, 5603.383627800979, 5620.325560932673, 5650.632708873951, 5678.545716342724, 4041.8787436376956, 4055.4322394864744, 4061.5735347788354, 4063.8984025399304, 4064.471618689581, 5603.439656034364, 5782.306123265235, 5784.0, 5792.4497172768515, 5797.739101740432, 4049.392591111357, 4085.7946905662902, 4093.97953010239, 4096.549379568245, 4100.2414553307735, 4132.524759652679, 4158.313878210122, 4167.2290626233635, 4183.937240941388, 4184.937225941613, 4489.989752305771, 4710.62315014798, 4722.386089808303, 4737.549932756383, 4777.331173635694, 5641.248569701185, 5645.802396916089, 5673.234113394721, 5678.545716342683, 5685.488306052475, 1408.0284603623468, 1408.9084209526347, 1416.9291535423188, 1424.7649137892338, 1433.1616004637403, 905.7237542549453, 922.9017469211617, 926.3885835348772, 955.8709574207393, 991.1921783746224, 5362.731863406814, 5393.892122157535, 5400.0, 5414.83755487336, 5427.538659214011, 5642.915356269659, 5656.943430565724, 5667.688277144714, 5677.290338707661, 5681.4034526374135, 5218.191180367049, 5380.515753582196, 5390.434004429479, 5468.125099983983, 5550.472705093091, 3037.7873548851812, 3043.6347638283546, 3064.3564851381016, 3064.417760625474, 3072.2780146665514, 5761.654300741969, 5947.8401711666675, 5950.761969521257, 5955.553333500005, 5963.5825492216, 5895.145203945404, 5903.3801450847905, 5907.940920590739, 5910.586258961917, 5924.496417804448, 5932.677700487654, 5937.139114828412, 5956.880862382728, 6005.549583781321, 6020.157177995085, 5645.633033852787, 5768.307803063659, 5803.65178089316, 5804.738786754522, 5814.796482123105, 5625.240592520121, 5638.351589567253, 5678.630888992202, 5687.971560142173, 5708.0, 5722.628029178257, 5939.7921072763975, 5961.552883533839, 5969.641821490724, 5975.193348898017, 4102.589741025873, 4124.628783409507, 4128.0, 4136.131412403429, 4136.586341365839, 5492.203630473638, 5606.579506537108, 5803.0, 5809.709514524341, 5822.82531524055, 5596.720163991866, 5603.7758489659955, 5604.0, 5611.410801865745, 5617.157426385911, 5553.222548843246, 5562.471565201299, 5564.527015203591, 5585.385607583244, 5588.546977785723, 5235.214717792362, 5538.861528461821, 5543.972280138573, 5548.528375088002, 5559.833205003856, 5608.747606357642, 5636.2954630671165, 5641.576881733804, 5656.858578535405, 5704.600677952503, 5292.259083728204, 5565.165225216088, 5580.21876937221, 5598.412166722435, 5611.3266408031295], "value": 4274.039767596705, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.5741698523348, 746.1717493582124, 775.08539922891, 791.9247671471209, 822.3174764945096, 14390.49633262836, 15097.094174349519, 15165.31756070978, 15280.235988200588, 16252.51242462728, 14765.77851332231, 14867.182304973223, 15059.849401505982, 15159.090454572734, 15179.16514591697, 649.2339039932901, 758.1319389299276, 777.7355699062332, 798.2935102434359, 798.8482188384218, 794.0352471746827, 806.8144326804838, 807.2250639386167, 807.4105902691039, 808.1958451340895, 839.1734141870284, 840.7099550655045, 841.3227351981652, 842.3008902610818, 843.9915600843974, 674.3930462583689, 792.0970094092739, 813.2802469814197, 829.85062688716, 829.9087100418949, 597.3250227243187, 597.614538622586, 597.9103134529812, 597.9581429299951, 598.0341748076868, 14487.0, 14492.130472171704, 14531.200783956878, 14537.92731036338, 14572.12567245958, 14638.0, 14871.397505436506, 14878.404863805436, 14904.701905961929, 14925.328360223708, 11816.645500634995, 11922.284662920165, 11937.34344611046, 12247.816282755766, 12562.623121306284, 4546.680114640652, 4610.884727881797, 4668.603168730628, 4670.346151538787, 4721.787519561628, 79403.0, 80527.19472805255, 80854.59572702099, 81134.0, 81360.9999999994, 11312.0, 11601.825972610417, 11618.88381116195, 11897.226680265756, 12425.565105221274, 21975.340739777832, 22012.0, 22019.339419817432, 22030.11879524801, 22037.228696995528, 8809.911900881038, 8932.642694292155, 9400.811983760423, 9404.24766018717, 9411.529423528797, 4872.561469467694, 4945.332380128709, 4954.430240522386, 5078.644494885322, 5085.084684756779, 3411.26657768576, 3414.5219669246326, 3416.6924976751716, 3494.353544594258, 3555.9111022224133, 598.6049207523014, 599.3616798109999, 599.66718471248, 600.5465873265656, 602.8553147244718, 636.3445650979498, 658.8155316511385, 662.9171353580803, 676.6650507998572, 740.1155619035201, 8926.464412135292, 8965.661516392202, 9289.674861379819, 9539.379940303857, 9646.807063858823, 627.6171535363425, 638.7061951502268, 650.9682153786207, 696.8780463418882, 705.2383425900118, 4259.87220383389, 4265.723145424172, 4329.6536277098385, 4541.182587134347, 4662.300654901792, 69750.65124674344, 69824.3017569828, 70600.0, 83088.58455707804, 118119.67178708054, 99395.07259128951, 102884.0, 102934.0, 104269.0, 104287.4785626067, 52799.208011879855, 73063.63468182625, 85089.57455212684, 87151.0, 90364.09635903557, 7991.120976692443, 7997.400194985283, 8060.959695201486, 8221.588920553828, 8225.95887020561, 53531.46468535265, 53985.19022214592, 54994.450055498935, 55701.44298556963, 56337.71831140818, 52058.0, 73919.0, 74607.25392746003, 75635.0, 76017.6199119023, 11309.208355415041, 11349.262297950607, 11422.657320280405, 11735.354555499189, 12126.757464850478, 12236.327002014974, 12248.571300004813, 12443.688907777378, 12759.681007974503, 13030.804537932128, 11613.187076904536, 11743.823842642196, 12024.879751202727, 12153.392330383269, 12530.310832904277, 11567.438395816456, 11701.180917335705, 11798.233114847852, 12141.271523708605, 12200.694982625506, 10487.897050341026, 10500.947495262475, 10538.104261137583, 11441.713957151138, 11721.824172637587, 9602.231821454126, 9723.56243969009, 9759.0, 9908.752281193028, 10158.492075396052, 9192.632294708137, 9197.816043679222, 9341.813163736551, 9373.281886940144, 9397.671081512115], "value": 7221.6779878687275, "unit": "score"}], "name": "dromaeo_css", "value": 7116.569782232163, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 110.0, 111.0, 111.0, 111.0, 110.0, 109.0, 111.0, 109.0, 110.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 115.0, 118.0, 175.0, 117.0, 118.0, 138.0, 115.0, 147.0, 117.0], "value": 130.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 160.0, 160.0, 160.0, 161.0, 159.0, 160.0, 159.0, 161.0, 161.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 127.0, 75.0, 75.0, 75.0, 74.0, 111.0, 75.0], "value": 83.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 118.0, 83.0, 83.0, 84.0, 123.0, 84.0, 84.0, 130.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 116.0, 116.0, 116.0, 117.0, 116.0, 116.0, 117.0], "value": 116.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 154.0, 145.0, 145.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 52.0, 53.0, 55.0, 54.0, 54.0, 56.0, 53.0, 55.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 62.0, 62.0, 62.0, 62.0, 63.0, 62.0, 62.0, 62.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 75.0, 76.0, 78.0, 75.0, 75.0, 75.0, 75.0, 78.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 129.0, 128.0, 119.0, 123.0, 122.0, 128.0, 120.0, 163.0, 119.0], "value": 126.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 153.0, 160.0, 154.0, 160.0, 157.0, 159.0, 154.0, 156.0], "value": 157.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 64.0, 63.0, 65.0, 61.0, 63.0, 60.0, 61.0], "value": 62.4, "unit": "score"}], "name": "kraken", "value": 1475.8000000000002, "alertThreshold": 2.0}]} 23:22:40 INFO - Return code: 0 23:22:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:22:41 INFO - # TBPL SUCCESS # 23:22:41 INFO - Running post-action listener: _resource_record_post_action 23:22:41 INFO - ##### 23:22:41 INFO - ##### Finished run-tests step (success) 23:22:41 INFO - ##### 23:22:41 INFO - Running post-run listener: _resource_record_post_run 23:22:41 INFO - Total resource usage - Wall time: 1197s; CPU: 13.0%; Read bytes: 4096; Write bytes: 553402368; Read time: 8; Write time: 2257168 23:22:41 INFO - TinderboxPrint: CPU usage
13.1% 23:22:41 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:22:41 INFO - TinderboxPrint: I/O write bytes / time
553,402,368 / 2,257,168 23:22:41 INFO - TinderboxPrint: CPU idle
8,296.7 (86.8%) 23:22:41 INFO - TinderboxPrint: CPU user
1,193.4 (12.5%) 23:22:41 INFO - TinderboxPrint: Swap in / out
0 / 0 23:22:41 INFO - install - Wall time: 19s; CPU: 20.0%; Read bytes: 0; Write bytes: 257351680; Read time: 0; Write time: 1495924 23:22:41 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 0; Write bytes: 296050688; Read time: 0; Write time: 761244 23:22:41 INFO - Running post-run listener: _upload_blobber_files 23:22:41 INFO - Blob upload gear active. 23:22:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:22:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:22:42 INFO - (blobuploader) - INFO - Open directory for files ... 23:22:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:22:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:22:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:22:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:22:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:22:45 INFO - (blobuploader) - INFO - Done attempting. 23:22:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:22:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:22:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:22:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:22:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:22:46 INFO - (blobuploader) - INFO - Done attempting. 23:22:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:22:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:22:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:22:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:22:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:22:47 INFO - (blobuploader) - INFO - Done attempting. 23:22:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:22:47 INFO - Return code: 0 23:22:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:22:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:22:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c53a88529db7ecd679be14396fb64921d43f0d2edce1bb8ba9d8e29d3d6770be0a3ba4c6d866f3a5f0db5b9ce26363ff39f3da585649d2a121d671707b2bbd29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fcda6f786f89853e1eef9f34c2e012674d2c6d28895e3811a40d04cc0f55aa1914924095c141995ff29559fdcf0335a17436f40290a57ed6847b04f121fd283f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ae9ecf929cee96dc4769a323ed32e5cc220541b7050ea91a8d71e78d7d596988ebfd4809f1ead4d1731ca68f7186e206a18d74c3bffe0bba6d6f15de54db4a5"} 23:22:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:22:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:22:47 INFO - Contents: 23:22:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c53a88529db7ecd679be14396fb64921d43f0d2edce1bb8ba9d8e29d3d6770be0a3ba4c6d866f3a5f0db5b9ce26363ff39f3da585649d2a121d671707b2bbd29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fcda6f786f89853e1eef9f34c2e012674d2c6d28895e3811a40d04cc0f55aa1914924095c141995ff29559fdcf0335a17436f40290a57ed6847b04f121fd283f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ae9ecf929cee96dc4769a323ed32e5cc220541b7050ea91a8d71e78d7d596988ebfd4809f1ead4d1731ca68f7186e206a18d74c3bffe0bba6d6f15de54db4a5"} 23:22:47 INFO - Running post-run listener: copy_logs_to_upload_dir 23:22:47 INFO - Copying logs to upload dir... 23:22:47 INFO - mkdir: /builds/slave/test/build/upload/logs 23:22:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1244.664964 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-07-05 23:22:46.904705) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 23:22:46.907989) ========= 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=df17445c3590ff7897617a0e00000225-1467783915.276653-605315000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c53a88529db7ecd679be14396fb64921d43f0d2edce1bb8ba9d8e29d3d6770be0a3ba4c6d866f3a5f0db5b9ce26363ff39f3da585649d2a121d671707b2bbd29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fcda6f786f89853e1eef9f34c2e012674d2c6d28895e3811a40d04cc0f55aa1914924095c141995ff29559fdcf0335a17436f40290a57ed6847b04f121fd283f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ae9ecf929cee96dc4769a323ed32e5cc220541b7050ea91a8d71e78d7d596988ebfd4809f1ead4d1731ca68f7186e206a18d74c3bffe0bba6d6f15de54db4a5"} build_url:https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/c9ohSk8ATaS__Z6xrYWVVQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c53a88529db7ecd679be14396fb64921d43f0d2edce1bb8ba9d8e29d3d6770be0a3ba4c6d866f3a5f0db5b9ce26363ff39f3da585649d2a121d671707b2bbd29", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fcda6f786f89853e1eef9f34c2e012674d2c6d28895e3811a40d04cc0f55aa1914924095c141995ff29559fdcf0335a17436f40290a57ed6847b04f121fd283f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0ae9ecf929cee96dc4769a323ed32e5cc220541b7050ea91a8d71e78d7d596988ebfd4809f1ead4d1731ca68f7186e206a18d74c3bffe0bba6d6f15de54db4a5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-05 23:22:46.933326) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:22:46.933672) ========= 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=df17445c3590ff7897617a0e00000225-1467783915.276653-605315000 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004447 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-05 23:22:47.015317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 23:22:47.015639) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-05 23:22:47.015922) ========= ========= Total master_lag: 0.18 =========