builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-039 starttime: 1468668717.29 results: success (0) buildid: 20160716013008 builduid: 27285102431645c8a69fac299db6c72f revision: 8027432e7f0a0cf52eaec04d72f67c25dd11446a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.294524) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.294957) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.295240) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.312265) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.312566) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-16 04:31:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-07-16 04:31:57 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114614 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.441006) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.441326) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021151 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:57.491258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-16 04:31:57.491567) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 8027432e7f0a0cf52eaec04d72f67c25dd11446a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 8027432e7f0a0cf52eaec04d72f67c25dd11446a --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=5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-16 04:31:57,649 truncating revision to first 12 chars 2016-07-16 04:31:57,649 Setting DEBUG logging. 2016-07-16 04:31:57,649 attempt 1/10 2016-07-16 04:31:57,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/8027432e7f0a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-16 04:31:58,582 unpacking tar archive at: mozilla-inbound-8027432e7f0a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.257791 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-16 04:31:58.762497) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:58.762809) ========= script_repo_revision: 8027432e7f0a0cf52eaec04d72f67c25dd11446a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:58.763233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:58.763494) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 04:31:58.773571) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-07-16 04:31:58.773888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:31:59 INFO - MultiFileLogger online at 20160716 04:31:59 in /builds/slave/test 04:31:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 04:31:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:31:59 INFO - {'append_to_log': False, 04:31:59 INFO - 'base_work_dir': '/builds/slave/test', 04:31:59 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 04:31:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:31:59 INFO - 'branch': 'Mozilla-Inbound', 04:31:59 INFO - 'buildbot_json_path': 'buildprops.json', 04:31:59 INFO - 'config_files': ('talos/linux_config.py',), 04:31:59 INFO - 'default_actions': ('clobber', 04:31:59 INFO - 'read-buildbot-config', 04:31:59 INFO - 'download-and-extract', 04:31:59 INFO - 'populate-webroot', 04:31:59 INFO - 'create-virtualenv', 04:31:59 INFO - 'install', 04:31:59 INFO - 'run-tests'), 04:31:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:31:59 INFO - 'download_minidump_stackwalk': True, 04:31:59 INFO - 'download_symbols': 'ondemand', 04:31:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:31:59 INFO - 'tooltool.py': '/tools/tooltool.py', 04:31:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:31:59 INFO - '/tools/misc-python/virtualenv.py')}, 04:31:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:31:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:31:59 INFO - 'installer_path': 'installer.exe', 04:31:59 INFO - 'log_level': 'info', 04:31:59 INFO - 'log_name': 'talos', 04:31:59 INFO - 'log_to_console': True, 04:31:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:31:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:31:59 INFO - 'opt_config_files': (), 04:31:59 INFO - 'pip_index': False, 04:31:59 INFO - 'sps_profile': False, 04:31:59 INFO - 'sps_profile_interval': 0, 04:31:59 INFO - 'suite': 'dromaeojs', 04:31:59 INFO - 'system_bits': '32', 04:31:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:31:59 INFO - 'title': 'talos-linux64-ix-039', 04:31:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:31:59 INFO - 'use_talos_json': True, 04:31:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:31:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:31:59 INFO - 'work_dir': 'build'} 04:31:59 INFO - ##### 04:31:59 INFO - ##### Running clobber step. 04:31:59 INFO - ##### 04:31:59 INFO - Running pre-action listener: _resource_record_pre_action 04:31:59 INFO - Running main action method: clobber 04:31:59 INFO - rmtree: /builds/slave/test/build 04:31:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:31:59 INFO - Running post-action listener: _resource_record_post_action 04:31:59 INFO - ##### 04:31:59 INFO - ##### Finished clobber step (success) 04:31:59 INFO - ##### 04:31:59 INFO - ##### 04:31:59 INFO - ##### Running read-buildbot-config step. 04:31:59 INFO - ##### 04:31:59 INFO - Running pre-action listener: _resource_record_pre_action 04:31:59 INFO - Running main action method: read_buildbot_config 04:31:59 INFO - Using buildbot properties: 04:31:59 INFO - { 04:31:59 INFO - "project": "", 04:31:59 INFO - "product": "firefox", 04:31:59 INFO - "script_repo_revision": "production", 04:31:59 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 04:31:59 INFO - "repository": "", 04:31:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 04:31:59 INFO - "buildid": "20160716013008", 04:31:59 INFO - "pgo_build": "True", 04:31:59 INFO - "basedir": "/builds/slave/test", 04:31:59 INFO - "buildnumber": 1245, 04:31:59 INFO - "slavename": "talos-linux64-ix-039", 04:31:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:31:59 INFO - "platform": "ubuntu64_hw", 04:31:59 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 04:31:59 INFO - "branch": "mozilla-inbound", 04:31:59 INFO - "repo_path": "integration/mozilla-inbound", 04:31:59 INFO - "revision": "8027432e7f0a0cf52eaec04d72f67c25dd11446a", 04:31:59 INFO - "stage_platform": "linux64-pgo", 04:31:59 INFO - "builduid": "27285102431645c8a69fac299db6c72f", 04:31:59 INFO - "slavebuilddir": "test" 04:31:59 INFO - } 04:31:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 04:31:59 INFO - Running post-action listener: _resource_record_post_action 04:31:59 INFO - ##### 04:31:59 INFO - ##### Finished read-buildbot-config step (success) 04:31:59 INFO - ##### 04:31:59 INFO - ##### 04:31:59 INFO - ##### Running download-and-extract step. 04:31:59 INFO - ##### 04:31:59 INFO - Running pre-action listener: _resource_record_pre_action 04:31:59 INFO - Running main action method: download_and_extract 04:31:59 INFO - mkdir: /builds/slave/test/build/tests 04:31:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:31:59 INFO - https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:31:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:31:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:31:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:31:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:00 INFO - Downloaded 1361 bytes. 04:32:00 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 04:32:00 INFO - Using the following test package requirements: 04:32:00 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:32:00 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 04:32:00 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 04:32:00 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'jsshell-linux-x86_64.zip'], 04:32:00 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 04:32:00 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 04:32:00 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 04:32:00 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 04:32:00 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 04:32:00 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 04:32:00 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 04:32:00 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:32:00 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')]} 04:32:00 INFO - https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:32:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:32:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:32:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:02 INFO - Downloaded 24333595 bytes. 04:32:02 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'] 04:32:02 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 04:32:03 INFO - Return code: 0 04:32:03 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 04:32: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')]} 04:32:03 INFO - https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:32:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:32:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 04:32:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:05 INFO - Downloaded 24333595 bytes. 04:32:05 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'] 04:32:05 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 04:32:07 INFO - Return code: 0 04:32:07 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')]} 04:32:07 INFO - https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:32:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:32:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 04:32:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:08 INFO - Downloaded 14403317 bytes. 04:32:08 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'] 04:32:08 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 04:32:08 INFO - Return code: 0 04:32: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')]} 04:32:08 INFO - https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:32:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:32:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:32:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/S8S5EdfJS9CVdie44df4Lw/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 04:32:11 INFO - Downloaded 60833174 bytes. 04:32:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:32:11 INFO - mkdir: /builds/slave/test/properties 04:32:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:32:11 INFO - Writing to file /builds/slave/test/properties/build_url 04:32:11 INFO - Contents: 04:32:11 INFO - build_url:https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 04:32:12 INFO - Running post-action listener: _resource_record_post_action 04:32:12 INFO - Running post-action listener: set_extra_try_arguments 04:32:12 INFO - ##### 04:32:12 INFO - ##### Finished download-and-extract step (success) 04:32:12 INFO - ##### 04:32:12 INFO - ##### 04:32:12 INFO - ##### Running populate-webroot step. 04:32:12 INFO - ##### 04:32:12 INFO - Running pre-action listener: _resource_record_pre_action 04:32:12 INFO - Running main action method: populate_webroot 04:32:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:32:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:32:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:32:12 INFO - '--tppagecycles', 04:32:12 INFO - '7'], 04:32:12 INFO - 'tests': ['tsvgm']}}, 04:32:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:32:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:32:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:32:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:32:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:32:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['damp', 'tps']}, 04:32:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'tests': ['damp', 'tps']}, 04:32:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['dromaeo_dom']}, 04:32:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:32:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['basic_compositor_video']}, 04:32:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:32:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['a11yr', 04:32:12 INFO - 'ts_paint', 04:32:12 INFO - 'tpaint', 04:32:12 INFO - 'sessionrestore', 04:32:12 INFO - 'sessionrestore_no_auto_restore', 04:32:12 INFO - 'tabpaint']}, 04:32:12 INFO - 'other-e10s': {'tests': ['a11yr', 04:32:12 INFO - 'ts_paint', 04:32:12 INFO - 'tpaint', 04:32:12 INFO - 'sessionrestore', 04:32:12 INFO - 'sessionrestore_no_auto_restore', 04:32:12 INFO - 'tabpaint']}, 04:32:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:32:12 INFO - 'ts_paint', 04:32:12 INFO - 'tpaint', 04:32:12 INFO - 'sessionrestore', 04:32:12 INFO - 'sessionrestore_no_auto_restore', 04:32:12 INFO - 'tabpaint']}, 04:32:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:32:12 INFO - 'ts_paint', 04:32:12 INFO - 'tpaint', 04:32:12 INFO - 'sessionrestore', 04:32:12 INFO - 'sessionrestore_no_auto_restore', 04:32:12 INFO - 'tabpaint']}, 04:32:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['a11yr', 04:32:12 INFO - 'ts_paint', 04:32:12 INFO - 'tpaint', 04:32:12 INFO - 'sessionrestore', 04:32:12 INFO - 'sessionrestore_no_auto_restore', 04:32:12 INFO - 'tabpaint']}, 04:32:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['a11yr', 04:32:12 INFO - 'ts_paint', 04:32:12 INFO - 'tpaint', 04:32:12 INFO - 'sessionrestore', 04:32:12 INFO - 'sessionrestore_no_auto_restore', 04:32:12 INFO - 'tabpaint']}, 04:32:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['tsvgx', 04:32:12 INFO - 'tsvgr_opacity', 04:32:12 INFO - 'tart', 04:32:12 INFO - 'tscrollx', 04:32:12 INFO - 'cart']}, 04:32:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:32:12 INFO - 'tsvgr_opacity', 04:32:12 INFO - 'tart', 04:32:12 INFO - 'tscrollx', 04:32:12 INFO - 'cart']}, 04:32:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'talos_options': ['--disable-e10s'], 04:32:12 INFO - 'tests': ['tp5o']}, 04:32:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'tests': ['tp5o']}, 04:32:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'talos_options': ['--disable-e10s', 04:32:12 INFO - '--xperf_path', 04:32:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:12 INFO - 'tests': ['tp5n']}, 04:32:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:32:12 INFO - 'talos_options': ['--xperf_path', 04:32:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:32:12 INFO - 'tests': ['tp5n']}}, 04:32:12 INFO - 'talos.zip': {'path': '', 04:32:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:32:12 INFO - Running post-action listener: _resource_record_post_action 04:32:12 INFO - ##### 04:32:12 INFO - ##### Finished populate-webroot step (success) 04:32:12 INFO - ##### 04:32:12 INFO - ##### 04:32:12 INFO - ##### Running create-virtualenv step. 04:32:12 INFO - ##### 04:32:12 INFO - Running pre-action listener: _resource_record_pre_action 04:32:12 INFO - Running main action method: create_virtualenv 04:32:12 INFO - Creating virtualenv /builds/slave/test/build/venv 04:32:12 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 04:32:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:32:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:32:12 INFO - Using real prefix '/usr' 04:32:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:32:13 INFO - Installing distribute.............................................................................................................................................................................................done. 04:32:15 INFO - Installing pip.................done. 04:32:15 INFO - Return code: 0 04:32:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:32:15 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')]} 04:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:15 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')]} 04:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:15 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:15 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 04:32:15 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 04:32:15 INFO - Using env: {'DISPLAY': ':0', 04:32:15 INFO - 'HOME': '/home/cltbld', 04:32:15 INFO - 'LANG': 'en_US.UTF-8', 04:32:15 INFO - 'LANGUAGE': 'en_US:en', 04:32:15 INFO - 'LOGNAME': 'cltbld', 04:32:15 INFO - 'MAIL': '/var/mail/cltbld', 04:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:15 INFO - 'MOZ_NO_REMOTE': '1', 04:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:15 INFO - 'NO_EM_RESTART': '1', 04:32:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:15 INFO - 'PWD': '/builds/slave/test', 04:32:15 INFO - 'SHELL': '/bin/bash', 04:32:15 INFO - 'SHLVL': '1', 04:32:15 INFO - 'TERM': 'linux', 04:32:15 INFO - 'TMOUT': '86400', 04:32:15 INFO - 'USER': 'cltbld', 04:32:15 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:15 INFO - '_': '/tools/buildbot/bin/python'} 04:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:15 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 04:32:15 INFO - Cleaning up... 04:32:15 INFO - Return code: 0 04:32:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:32:15 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')]} 04:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:15 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')]} 04:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:15 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:15 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 04:32:15 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 04:32:15 INFO - Using env: {'DISPLAY': ':0', 04:32:15 INFO - 'HOME': '/home/cltbld', 04:32:15 INFO - 'LANG': 'en_US.UTF-8', 04:32:15 INFO - 'LANGUAGE': 'en_US:en', 04:32:15 INFO - 'LOGNAME': 'cltbld', 04:32:15 INFO - 'MAIL': '/var/mail/cltbld', 04:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:15 INFO - 'MOZ_NO_REMOTE': '1', 04:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:15 INFO - 'NO_EM_RESTART': '1', 04:32:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:15 INFO - 'PWD': '/builds/slave/test', 04:32:15 INFO - 'SHELL': '/bin/bash', 04:32:15 INFO - 'SHLVL': '1', 04:32:15 INFO - 'TERM': 'linux', 04:32:15 INFO - 'TMOUT': '86400', 04:32:15 INFO - 'USER': 'cltbld', 04:32:15 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:15 INFO - '_': '/tools/buildbot/bin/python'} 04:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:15 INFO - Downloading/unpacking psutil>=3.1.1 04:32:15 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 04:32:15 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 04:32:15 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 04:32:15 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 04:32:15 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 04:32:15 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 04:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:32:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:17 INFO - Installing collected packages: psutil 04:32:17 INFO - Running setup.py install for psutil 04:32:17 INFO - building 'psutil._psutil_linux' extension 04:32:17 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 04:32:17 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 04:32:17 INFO - building 'psutil._psutil_posix' extension 04:32:17 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 04:32:18 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 04:32:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:32:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:32:18 INFO - Successfully installed psutil 04:32:18 INFO - Cleaning up... 04:32:18 INFO - Return code: 0 04:32:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:18 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:18 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 04:32:18 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 04:32:18 INFO - Using env: {'DISPLAY': ':0', 04:32:18 INFO - 'HOME': '/home/cltbld', 04:32:18 INFO - 'LANG': 'en_US.UTF-8', 04:32:18 INFO - 'LANGUAGE': 'en_US:en', 04:32:18 INFO - 'LOGNAME': 'cltbld', 04:32:18 INFO - 'MAIL': '/var/mail/cltbld', 04:32:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:18 INFO - 'MOZ_NO_REMOTE': '1', 04:32:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:18 INFO - 'NO_EM_RESTART': '1', 04:32:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:18 INFO - 'PWD': '/builds/slave/test', 04:32:18 INFO - 'SHELL': '/bin/bash', 04:32:18 INFO - 'SHLVL': '1', 04:32:18 INFO - 'TERM': 'linux', 04:32:18 INFO - 'TMOUT': '86400', 04:32:18 INFO - 'USER': 'cltbld', 04:32:18 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:18 INFO - '_': '/tools/buildbot/bin/python'} 04:32:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:32:18 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 04:32:18 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 04:32:18 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 04:32:18 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 04:32:18 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 04:32:18 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 04:32:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:32:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:32:20 INFO - Installing collected packages: mozsystemmonitor 04:32:20 INFO - Running setup.py install for mozsystemmonitor 04:32:20 INFO - Successfully installed mozsystemmonitor 04:32:20 INFO - Cleaning up... 04:32:20 INFO - Return code: 0 04:32:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:32:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:20 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:20 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 04:32:20 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 04:32:20 INFO - Using env: {'DISPLAY': ':0', 04:32:20 INFO - 'HOME': '/home/cltbld', 04:32:20 INFO - 'LANG': 'en_US.UTF-8', 04:32:20 INFO - 'LANGUAGE': 'en_US:en', 04:32:20 INFO - 'LOGNAME': 'cltbld', 04:32:20 INFO - 'MAIL': '/var/mail/cltbld', 04:32:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:20 INFO - 'MOZ_NO_REMOTE': '1', 04:32:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:20 INFO - 'NO_EM_RESTART': '1', 04:32:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:20 INFO - 'PWD': '/builds/slave/test', 04:32:20 INFO - 'SHELL': '/bin/bash', 04:32:20 INFO - 'SHLVL': '1', 04:32:20 INFO - 'TERM': 'linux', 04:32:20 INFO - 'TMOUT': '86400', 04:32:20 INFO - 'USER': 'cltbld', 04:32:20 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:20 INFO - '_': '/tools/buildbot/bin/python'} 04:32:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:20 INFO - Downloading/unpacking jsonschema==2.5.1 04:32:20 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 04:32:20 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 04:32:20 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 04:32:20 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 04:32:20 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 04:32:20 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 04:32:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:32:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:22 INFO - Downloading functools32-3.2.3-2.tar.gz 04:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:32:22 INFO - warning: no files found matching '*.txt' 04:32:22 INFO - no previously-included directories found matching 'build' 04:32:22 INFO - no previously-included directories found matching 'dist' 04:32:22 INFO - no previously-included directories found matching '.git*' 04:32:22 INFO - Installing collected packages: jsonschema, functools32 04:32:22 INFO - Running setup.py install for functools32 04:32:23 INFO - warning: no files found matching '*.txt' 04:32:23 INFO - no previously-included directories found matching 'build' 04:32:23 INFO - no previously-included directories found matching 'dist' 04:32:23 INFO - no previously-included directories found matching '.git*' 04:32:23 INFO - Successfully installed jsonschema functools32 04:32:23 INFO - Cleaning up... 04:32:23 INFO - Return code: 0 04:32:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:32:23 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')]} 04:32:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:23 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')]} 04:32:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:23 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:23 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 04:32:23 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 04:32:23 INFO - Using env: {'DISPLAY': ':0', 04:32:23 INFO - 'HOME': '/home/cltbld', 04:32:23 INFO - 'LANG': 'en_US.UTF-8', 04:32:23 INFO - 'LANGUAGE': 'en_US:en', 04:32:23 INFO - 'LOGNAME': 'cltbld', 04:32:23 INFO - 'MAIL': '/var/mail/cltbld', 04:32:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:23 INFO - 'MOZ_NO_REMOTE': '1', 04:32:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:23 INFO - 'NO_EM_RESTART': '1', 04:32:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:23 INFO - 'PWD': '/builds/slave/test', 04:32:23 INFO - 'SHELL': '/bin/bash', 04:32:23 INFO - 'SHLVL': '1', 04:32:23 INFO - 'TERM': 'linux', 04:32:23 INFO - 'TMOUT': '86400', 04:32:23 INFO - 'USER': 'cltbld', 04:32:23 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:23 INFO - '_': '/tools/buildbot/bin/python'} 04:32:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:23 INFO - Downloading/unpacking blobuploader==1.2.4 04:32:23 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 04:32:23 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 04:32:23 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 04:32:23 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 04:32:23 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 04:32:23 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 04:32:25 INFO - Downloading blobuploader-1.2.4.tar.gz 04:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:32:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:32:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32: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 04:32:26 INFO - Downloading docopt-0.6.1.tar.gz 04:32:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:32:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:32:26 INFO - Running setup.py install for blobuploader 04:32:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:32:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:32:26 INFO - Running setup.py install for requests 04:32:26 INFO - Running setup.py install for docopt 04:32:26 INFO - Successfully installed blobuploader requests docopt 04:32:26 INFO - Cleaning up... 04:32:26 INFO - Return code: 0 04:32:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:26 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:26 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 04:32:26 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 04:32:26 INFO - Using env: {'DISPLAY': ':0', 04:32:26 INFO - 'HOME': '/home/cltbld', 04:32:26 INFO - 'LANG': 'en_US.UTF-8', 04:32:26 INFO - 'LANGUAGE': 'en_US:en', 04:32:26 INFO - 'LOGNAME': 'cltbld', 04:32:26 INFO - 'MAIL': '/var/mail/cltbld', 04:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:26 INFO - 'MOZ_NO_REMOTE': '1', 04:32:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:26 INFO - 'NO_EM_RESTART': '1', 04:32:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:26 INFO - 'PWD': '/builds/slave/test', 04:32:26 INFO - 'SHELL': '/bin/bash', 04:32:26 INFO - 'SHLVL': '1', 04:32:26 INFO - 'TERM': 'linux', 04:32:26 INFO - 'TMOUT': '86400', 04:32:26 INFO - 'USER': 'cltbld', 04:32:26 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:26 INFO - '_': '/tools/buildbot/bin/python'} 04:32:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:27 INFO - Running setup.py (path:/tmp/pip-l7SI3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:27 INFO - Running setup.py (path:/tmp/pip-Gs2RpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:27 INFO - Running setup.py (path:/tmp/pip-avOV9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:27 INFO - Running setup.py (path:/tmp/pip-y8XQP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:27 INFO - Running setup.py (path:/tmp/pip-Fwh6Oq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:27 INFO - Running setup.py (path:/tmp/pip-i0_fJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:27 INFO - Running setup.py (path:/tmp/pip-kV2ZYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:27 INFO - Running setup.py (path:/tmp/pip-up2mwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:27 INFO - Running setup.py (path:/tmp/pip-QL9Zgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:27 INFO - Running setup.py (path:/tmp/pip-pNulJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:27 INFO - Running setup.py (path:/tmp/pip-nmI6IK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:27 INFO - Running setup.py (path:/tmp/pip-R67Jno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:27 INFO - Running setup.py (path:/tmp/pip-jEe7BI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:27 INFO - Running setup.py (path:/tmp/pip-XZml5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:28 INFO - Running setup.py (path:/tmp/pip-N1SzR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:28 INFO - Running setup.py (path:/tmp/pip-lk1lfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:28 INFO - Running setup.py (path:/tmp/pip-D2szPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:32:28 INFO - Running setup.py install for manifestparser 04:32:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:32:28 INFO - Running setup.py install for mozcrash 04:32:28 INFO - Running setup.py install for mozdebug 04:32:28 INFO - Running setup.py install for mozdevice 04:32:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:32:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:32:28 INFO - Running setup.py install for mozfile 04:32:28 INFO - Running setup.py install for mozhttpd 04:32:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:32:28 INFO - Running setup.py install for mozinfo 04:32:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:32:28 INFO - Running setup.py install for mozInstall 04:32:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Running setup.py install for mozleak 04:32:29 INFO - Running setup.py install for mozlog 04:32:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Running setup.py install for moznetwork 04:32:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Running setup.py install for mozprocess 04:32:29 INFO - Running setup.py install for mozprofile 04:32:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Running setup.py install for mozrunner 04:32:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:32:29 INFO - Running setup.py install for mozscreenshot 04:32:29 INFO - Running setup.py install for moztest 04:32:29 INFO - Running setup.py install for mozversion 04:32:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:32:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:32:30 INFO - Cleaning up... 04:32:30 INFO - Return code: 0 04:32:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32: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')]} 04:32:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32: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')]} 04:32:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:30 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:30 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 04:32:30 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 04:32:30 INFO - Using env: {'DISPLAY': ':0', 04:32:30 INFO - 'HOME': '/home/cltbld', 04:32:30 INFO - 'LANG': 'en_US.UTF-8', 04:32:30 INFO - 'LANGUAGE': 'en_US:en', 04:32:30 INFO - 'LOGNAME': 'cltbld', 04:32:30 INFO - 'MAIL': '/var/mail/cltbld', 04:32:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:30 INFO - 'MOZ_NO_REMOTE': '1', 04:32:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:30 INFO - 'NO_EM_RESTART': '1', 04:32:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:30 INFO - 'PWD': '/builds/slave/test', 04:32:30 INFO - 'SHELL': '/bin/bash', 04:32:30 INFO - 'SHLVL': '1', 04:32:30 INFO - 'TERM': 'linux', 04:32:30 INFO - 'TMOUT': '86400', 04:32:30 INFO - 'USER': 'cltbld', 04:32:30 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:30 INFO - '_': '/tools/buildbot/bin/python'} 04:32:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:32:30 INFO - Running setup.py (path:/tmp/pip-brelIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:32:30 INFO - Running setup.py (path:/tmp/pip-8m7hco-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:32:30 INFO - Running setup.py (path:/tmp/pip-PYMQjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:32:30 INFO - Running setup.py (path:/tmp/pip-9re_qN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:32:30 INFO - Running setup.py (path:/tmp/pip-gig6iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:32:30 INFO - Running setup.py (path:/tmp/pip-GYVe5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:32:30 INFO - Running setup.py (path:/tmp/pip-cVOAi_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:32:30 INFO - Running setup.py (path:/tmp/pip-7hzFPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:32:30 INFO - Running setup.py (path:/tmp/pip-cw00kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:32:30 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)) 04:32:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:32:30 INFO - Running setup.py (path:/tmp/pip-EgyEaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:32:31 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)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:32:31 INFO - Running setup.py (path:/tmp/pip-1FMl3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:32:31 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)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:32:31 INFO - Running setup.py (path:/tmp/pip-Iii2up-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:32:31 INFO - Running setup.py (path:/tmp/pip-pPXRLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:32:31 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)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:32:31 INFO - Running setup.py (path:/tmp/pip-BAIvny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:31 INFO - Running setup.py (path:/tmp/pip-TS_6UO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:32:31 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)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:32:31 INFO - Running setup.py (path:/tmp/pip-jdpnd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:32:31 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)) 04:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:32:31 INFO - Running setup.py (path:/tmp/pip-R2Cp5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:32:31 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)) 04:32:31 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)) 04:32:31 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)) 04:32:31 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)) 04:32:31 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)) 04:32:31 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)) 04:32:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:32:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:32:33 INFO - Downloading blessings-1.6.tar.gz 04:32:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:32:33 INFO - Installing collected packages: blessings 04:32:33 INFO - Running setup.py install for blessings 04:32:33 INFO - Successfully installed blessings 04:32:33 INFO - Cleaning up... 04:32:33 INFO - Return code: 0 04:32:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:32:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:33 INFO - Reading from file tmpfile_stdout 04:32:33 INFO - Current package versions: 04:32:33 INFO - argparse == 1.2.1 04:32:33 INFO - blessings == 1.6 04:32:33 INFO - blobuploader == 1.2.4 04:32:33 INFO - docopt == 0.6.1 04:32:33 INFO - functools32 == 3.2.3-2 04:32:33 INFO - jsonschema == 2.5.1 04:32:33 INFO - manifestparser == 1.1 04:32:33 INFO - mozInstall == 1.12 04:32:33 INFO - mozcrash == 0.17 04:32:33 INFO - mozdebug == 0.1 04:32:33 INFO - mozdevice == 0.48 04:32:33 INFO - mozfile == 1.2 04:32:33 INFO - mozhttpd == 0.7 04:32:33 INFO - mozinfo == 0.9 04:32:33 INFO - mozleak == 0.1 04:32:33 INFO - mozlog == 3.2 04:32:33 INFO - moznetwork == 0.27 04:32:33 INFO - mozprocess == 0.23 04:32:33 INFO - mozprofile == 0.28 04:32:33 INFO - mozrunner == 6.12 04:32:33 INFO - mozscreenshot == 0.1 04:32:33 INFO - mozsystemmonitor == 0.3 04:32:33 INFO - moztest == 0.7 04:32:33 INFO - mozversion == 1.4 04:32:33 INFO - psutil == 3.1.1 04:32:33 INFO - requests == 1.2.3 04:32:33 INFO - wsgiref == 0.1.2 04:32:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:32:33 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')]} 04:32:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:33 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')]} 04:32:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:33 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 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:33 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 04:32:33 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 04:32:33 INFO - Using env: {'DISPLAY': ':0', 04:32:33 INFO - 'HOME': '/home/cltbld', 04:32:33 INFO - 'LANG': 'en_US.UTF-8', 04:32:33 INFO - 'LANGUAGE': 'en_US:en', 04:32:33 INFO - 'LOGNAME': 'cltbld', 04:32:33 INFO - 'MAIL': '/var/mail/cltbld', 04:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:33 INFO - 'MOZ_NO_REMOTE': '1', 04:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:33 INFO - 'NO_EM_RESTART': '1', 04:32:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:33 INFO - 'PWD': '/builds/slave/test', 04:32:33 INFO - 'SHELL': '/bin/bash', 04:32:33 INFO - 'SHLVL': '1', 04:32:33 INFO - 'TERM': 'linux', 04:32:33 INFO - 'TMOUT': '86400', 04:32:33 INFO - 'USER': 'cltbld', 04:32:33 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:33 INFO - '_': '/tools/buildbot/bin/python'} 04:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 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)) 04:32:34 INFO - Cleaning up... 04:32:34 INFO - Return code: 0 04:32:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:32:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:32:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:32:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:32:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:32:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xc3d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xefe180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, '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': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', '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 04:32:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:32:34 INFO - Using env: {'DISPLAY': ':0', 04:32:34 INFO - 'HOME': '/home/cltbld', 04:32:34 INFO - 'LANG': 'en_US.UTF-8', 04:32:34 INFO - 'LANGUAGE': 'en_US:en', 04:32:34 INFO - 'LOGNAME': 'cltbld', 04:32:34 INFO - 'MAIL': '/var/mail/cltbld', 04:32:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:34 INFO - 'MOZ_NO_REMOTE': '1', 04:32:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:34 INFO - 'NO_EM_RESTART': '1', 04:32:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:34 INFO - 'PWD': '/builds/slave/test', 04:32:34 INFO - 'SHELL': '/bin/bash', 04:32:34 INFO - 'SHLVL': '1', 04:32:34 INFO - 'TERM': 'linux', 04:32:34 INFO - 'TMOUT': '86400', 04:32:34 INFO - 'USER': 'cltbld', 04:32:34 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:34 INFO - '_': '/tools/buildbot/bin/python'} 04:32:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:32:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:32:34 INFO - Cleaning up... 04:32:34 INFO - Return code: 0 04:32:34 INFO - Running post-action listener: _resource_record_post_action 04:32:34 INFO - Running post-action listener: _start_resource_monitoring 04:32:34 INFO - Starting resource monitoring. 04:32:34 INFO - ##### 04:32:34 INFO - ##### Finished create-virtualenv step (success) 04:32:34 INFO - ##### 04:32:34 INFO - ##### 04:32:34 INFO - ##### Running install step. 04:32:34 INFO - ##### 04:32:34 INFO - Running pre-action listener: _resource_record_pre_action 04:32:34 INFO - Running main action method: install 04:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:32:34 INFO - Reading from file tmpfile_stdout 04:32:34 INFO - Detecting whether we're running mozinstall >=1.0... 04:32:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:32:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:32:34 INFO - Reading from file tmpfile_stdout 04:32:34 INFO - Output received: 04:32:34 INFO - Usage: mozinstall [options] installer 04:32:34 INFO - Options: 04:32:34 INFO - -h, --help show this help message and exit 04:32:34 INFO - -d DEST, --destination=DEST 04:32:34 INFO - Directory to install application into. [default: 04:32:34 INFO - "/builds/slave/test"] 04:32:34 INFO - --app=APP Application being installed. [default: firefox] 04:32:34 INFO - mkdir: /builds/slave/test/build/application 04:32:34 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'] 04:32:34 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 04:32:51 INFO - Reading from file tmpfile_stdout 04:32:51 INFO - Output received: 04:32:51 INFO - /builds/slave/test/build/application/firefox/firefox 04:32:51 INFO - Running post-action listener: _resource_record_post_action 04:32:51 INFO - ##### 04:32:51 INFO - ##### Finished install step (success) 04:32:51 INFO - ##### 04:32:51 INFO - ##### 04:32:51 INFO - ##### Running run-tests step. 04:32:51 INFO - ##### 04:32:51 INFO - Running pre-action listener: _resource_record_pre_action 04:32:51 INFO - Running main action method: run_tests 04:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:32:51 INFO - Python 2.7.3 04:32:51 INFO - Return code: 0 04:32:51 INFO - grabbing minidump binary from tooltool 04:32:51 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')]} 04:32:51 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 0xefc3b0>, '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 0xefd2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xefd740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:32:51 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 04:32:51 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 04:32:51 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 04:32:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:32:51 INFO - Return code: 0 04:32:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:32:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:32:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:32:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:32:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:32:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 04:32:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-039 --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 04:32:51 INFO - Using env: {'DISPLAY': ':0', 04:32:51 INFO - 'HOME': '/home/cltbld', 04:32:51 INFO - 'LANG': 'en_US.UTF-8', 04:32:51 INFO - 'LANGUAGE': 'en_US:en', 04:32:51 INFO - 'LOGNAME': 'cltbld', 04:32:51 INFO - 'MAIL': '/var/mail/cltbld', 04:32:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:32:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:32:51 INFO - 'MOZ_NO_REMOTE': '1', 04:32:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:32:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:32:51 INFO - 'NO_EM_RESTART': '1', 04:32:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:32:51 INFO - 'PWD': '/builds/slave/test', 04:32:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:32:51 INFO - 'SHELL': '/bin/bash', 04:32:51 INFO - 'SHLVL': '1', 04:32:51 INFO - 'TERM': 'linux', 04:32:51 INFO - 'TMOUT': '86400', 04:32:51 INFO - 'USER': 'cltbld', 04:32:51 INFO - 'XDG_SESSION_COOKIE': '5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444', 04:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:32:51 INFO - '_': '/tools/buildbot/bin/python'} 04:32:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-039', '--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 04:32:52 INFO - mozversion application_buildid: 20160716013008 04:32:52 INFO - mozversion application_changeset: 8027432e7f0a0cf52eaec04d72f67c25dd11446a 04:32:52 INFO - mozversion application_display_name: Nightly 04:32:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:32:52 INFO - mozversion application_name: Firefox 04:32:52 INFO - mozversion application_remotingname: firefox 04:32:52 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:32:52 INFO - mozversion application_vendor: Mozilla 04:32:52 INFO - mozversion application_version: 50.0a1 04:32:52 INFO - mozversion platform_buildid: 20160716013008 04:32:52 INFO - mozversion platform_changeset: 8027432e7f0a0cf52eaec04d72f67c25dd11446a 04:32:52 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 04:32:52 INFO - mozversion platform_version: 50.0a1 04:32:52 INFO - using testdate: 1468668772 04:32:52 INFO - actual date: 1468668772 04:32:52 INFO - starting webserver on 'localhost:39567' 04:32:52 INFO - SUITE-START | Running 2 tests 04:32:52 INFO - TEST-START | dromaeo_css 04:32:52 INFO - operating with platform_type : linux_ 04:32:52 INFO - Initialising browser for dromaeo_css test... 04:32:52 INFO - TEST-INFO | started process 26786 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplAEbX3/profile http://localhost:39567/getInfo.html) 04:32:56 INFO - PROCESS | 26786 | __metrics Screen width/height:1600/1200 04:32:56 INFO - PROCESS | 26786 | colorDepth:24 04:32:56 INFO - PROCESS | 26786 | Browser inner width/height: 1024/697 04:32:56 INFO - PROCESS | 26786 | __metrics 04:32:57 INFO - PROCESS | 26786 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:32:57 INFO - PROCESS | 26786 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:32:58 INFO - PROCESS | 26786 | console.error: PushService: 04:32:58 INFO - PROCESS | 26786 | stateChangeProcessEnqueue: Error transitioning state 04:32:58 INFO - PROCESS | 26786 | UnknownError 04:32:58 INFO - TEST-INFO | 26786: exit 0 04:32:58 INFO - Browser initialized. 04:32:58 INFO - Running cycle 1/1 for dromaeo_css test... 04:32:58 INFO - TEST-INFO | started process 26869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplAEbX3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:33:10 INFO - PROCESS | 26869 | RSS: Main: 157433856 04:33:10 INFO - PROCESS | 26869 | 04:34:01 INFO - PROCESS | 26869 | 1468668841835 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] 04:36:09 INFO - PROCESS | 26869 | Cycle 1(1): loaded http://localhost:39567/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39567/tests/dromaeo/cssquery-ext.html) 04:36:10 INFO - PROCESS | 26869 | RSS: Main: 248389632 04:36:10 INFO - PROCESS | 26869 | 04:39:13 INFO - PROCESS | 26869 | Cycle 1(1): loaded http://localhost:39567/tests/dromaeo/cssquery-ext.html (next: http://localhost:39567/tests/dromaeo/cssquery-jquery.html) 04:39:13 INFO - PROCESS | 26869 | RSS: Main: 253632512 04:39:13 INFO - PROCESS | 26869 | 04:42:16 INFO - PROCESS | 26869 | Cycle 1(1): loaded http://localhost:39567/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39567/tests/dromaeo/cssquery-mootools.html) 04:42:16 INFO - PROCESS | 26869 | RSS: Main: 253251584 04:42:16 INFO - PROCESS | 26869 | 04:45:18 INFO - PROCESS | 26869 | Cycle 1(1): loaded http://localhost:39567/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39567/tests/dromaeo/cssquery-prototype.html) 04:45:19 INFO - PROCESS | 26869 | RSS: Main: 246788096 04:45:19 INFO - PROCESS | 26869 | 04:48:21 INFO - PROCESS | 26869 | Cycle 1(1): loaded http://localhost:39567/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39567/tests/dromaeo/cssquery-yui.html) 04:48:22 INFO - PROCESS | 26869 | RSS: Main: 207491072 04:48:22 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | Cycle 1(1): loaded http://localhost:39567/tests/dromaeo/cssquery-yui.html (next: http://localhost:39567/tests/dromaeo/cssquery-dojo.html) 04:51:25 INFO - PROCESS | 26869 | RSS: Main: 282345472 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | __start_tp_report 04:51:25 INFO - PROCESS | 26869 | _x_x_mozilla_page_load 04:51:25 INFO - PROCESS | 26869 | _x_x_mozilla_page_load_details 04:51:25 INFO - PROCESS | 26869 | |i|pagename|runs| 04:51:25 INFO - PROCESS | 26869 | |0;dojo.html;3042.285063010192;3068.953965690515;3092.5670406143136;3111.1288839125054;3127.061881435569;5314.734263286835;5321.920171197435;5356.464353564642;5435.864103397417;5474.151506516491;5416.1063424534905;5419.512243898049;5453.2092846537225;5478.096114141172;5510.504054635082;4838.080764654722;4864.464908860023;4900.019996000797;4915.041566894457;5087.313212716292;4987.152184128707;4993.675411098267;5036.445990940993;5049.3435853339015;5063.113955057868;4800.399950006249;4826.589739872115;4882.340883980671;4927.556519913189;4937.481564435719;4913.213885778258;4952.059108769322;4967.379077615298;5016.247562865563;5040.722760248222;4892.877678058041;4909.312696222496;4934.210526315772;4934.210526315772;4979.850604481871;5295.576353891679;5297.364316282071;5308.495692909168;5362.973185134049;5388.757505912244;5741.397153298885;5750.079987202028;5775.16260142277;5781.075027995501;5796.275465566804;5727.11229759388;5786.334571524287;5810.360860305363;5827.825165245049;5834.912476312858;5337.626366154371;5446.673199607996;5456.699881506515;5465.6447330923365;5527.281453411111;5942.673152976541;5956.791512297049;5958.76164953397;5959.404059594006;5963;5468.179773033997;5638.2670252866865;5671.546276297886;5683.9715801420725;5684.8294551163535;5811.128330750336;5821.94178058214;5822.184894114827;5874.324452687995;5912.970435147797;5783.248177736868;5838.036723940587;5841.065429531254;5844.298684157929;5885.440883116183;5568.248286481273;5668.57485688575;5681.517071049007;5850.912236316459;5859.501942334864;4375.037491751809;4383.101464199832;4386.12277544486;4422.447194100738;4425.977870110629;3903.746256493319;3913.8238779255003;3914.451976723261;3934.15415685629;3950.308695978192;5329.946700532945;5333.359996800331;5389.164679474688;5415;5464.972675136599;5839.591228614041;5851.590388672837;5860.472557469763;5871.4128587141795;5882.411758824083;5346.946530534645;5357.383900851374;5366.195070739421;5371.060064488699;5371.892562148735;4918.827841025547;4951.801927922843;4970.15507363749;5019.046381187636;5036.521530454601;6004.369541198199;6015.488683461867;6020.638761674313;6022.427869352405;6023.367546407564;5835.56233282497;5843.474087332161;5852.122181672783;5853.8536536585225;5857.824265271878;5795.246617939641;5927.792527261525;5980.222571065734;5993.70031498432;6074.483668888194;5712.257406537124;5758.193852860687;5760.510356619734;5773.1051686988585;5776.19133321327;5775.162601422686;5785.190073389645;5790.1893734877995;5799.159121927381;5807.738651760596;5506.449355064461;5658.349289831557;5711.800086997101;5722.370539240597;5729.742161602821;5712.685802280915;5713.028785106459;5715.914261286167;5718.142278658068;5722.885542289214;5538.667679939216;5637.2107904894165;5649.802256920988;5651.632643878134;5655.293088363955;5655.179998900218;5689.687067211343;5702.429757024264;5724.341700704473;5782.132680098019;5618.438156184349;5620.831375058755;5629.043062679436;5630.099184130683;5631.0427227372265 04:51:25 INFO - PROCESS | 26869 | |1;ext.html;609.5550248318727;640.1134428816089;641.9229692436905;645.5835985789163;646.7800947677786;43471.78264108679;45476.999999999956;45617.54382456175;49160.50839491604;49191.57275845173;46725.53274467254;46838.29742553864;47255.29117063238;47258.054838903205;47350.526494735044;8979.416337938046;9044.09559044097;9104.317176211778;9107.180353768159;9129.178373946343;8965.462072275684;9015.413998090136;9061.546922653873;9061.728148155533;9063.637454501844;9138.360314777969;9162.35863489556;9163.083691630816;9173.862392064124;9185.678501252423;9026.774330641754;9048.366614337;9069.86395204066;9074.410163339428;9085.273797978429;9027.503487308195;9106.81786364269;9109.544522773835;9113.772155696095;9113.997460279365;43744.78127609374;44216.3367549487;44230.55769442297;44258.33612495815;44268.778656106835;3422.9315413691834;3887.826009377568;4047.6154765297542;4064.7764372959477;4089.734167279117;32904.83547582271;33845.32309353799;34195.31609367799;34267;34326.65673343284;4339.566043395666;4539.568740969603;4548.408706868119;4623.12160689468;4635.605973492257;355976;359934;360384;362195;364553;60932.39067609356;61144.38855611387;61181;61286.38713612896;61475.38524614786;64355.67822160859;64402;64475.67762161253;64505.35494644994;64595.35404645988;16436.424725134802;16698.332066717438;16706.4152754653;16723.749143762852;16731.6653666926;13422.261775602436;13465.865341346656;13475.326233688276;13496.122752021283;13497.595072147851;16127;17918.91040544815;17925.7311140333;18111.45665630033;18142;5183.2743415921805;5918.378570250148;5959.67221802805;6058.727357268934;6090.208272924491;1030.0317701360846;1071.1912506058031;1085.6580177244143;1094.950727217277;1192.785298646252;32940.50589241163;32974;33185.66814331874;33568;34100.31799363999;1079.3092420850662;1086.5273605981424;1094.0809719835377;1135.846660700795;1150.7468356961456;15545.533633990999;15897.761533577006;15901.204939753197;15957.441489548042;16229;495080;496362;500654;502113;502365;60309.69845150834;60426;60474.69762651159;60583.39416605866;60759.69620151871;1240.1133189769178;1307.8888294495075;1342.5502456676927;1365.590322903125;1390.2840037380756;32898.67101328956;33940;33952.320953581286;33990.8300458506;34026.829865851505;51520.48479515157;52417;52495.73752131215;52513.21230181627;52626.47373526216;394687;401803;403492;403569;404711;24817.875910620332;27478.450430991667;28100.859495702392;28445.857770711013;30243.243918902204;48882.75558622184;49705;49826.50173498219;50310.74844625754;51209.48790512047;41684.166316674105;42223.366649500895;42242.57757422386;43127.13745725131;43861.342079869464;42245.577544224165;42964.78517607517;43019.56980430156;43062.56937430586;43438.782806085765;38188.618113818506;40665.796671016455;41718;41737.79131104325;41837;35929.46105808362;36452.27095458129;36456.453153202194;36586.45120323144;37186;27685.861570692017;27807.582886257125;27909.87868284229;28195.718042820128;28262.293442664097 04:51:25 INFO - PROCESS | 26869 | |2;jquery.html;392.6623104130449;392.78789453695003;392.79182033522244;399.8420623853578;421.57210431412125;13336.866631333683;19010.334638287663;20727.481812954684;20728.170873165072;21711.34865954024;15401.922990385032;19533.12100955457;19537.413877583695;19797.60404791907;20146.899265503653;5896.204012458317;6010.519158467312;6017.247844019497;6018.097285407202;6044.7279872405625;5982.401759824026;6011.939880601203;6021.397860213988;6027.939720602782;6044.727987240584;4250.723702959298;5105.591552675777;6709.6980635871505;6720.3951644352;6720.731170753164;6600.273969863335;6607.834804129887;6696.698648560802;6711.966440167792;6724.260331363546;5748.4538968798315;5837.9124313135335;5841.795537156179;5867.559933005042;5876.7355469004;19150.904245478683;23926;24633.63049554258;24667.876660616763;24969.126080587273;2370.3718514593647;2431.5866302728577;2509.5859183234743;2746.7665248453904;3122.1570176052337;14860.331285092088;14969.401223951028;15173.393064277416;15409.536094071045;15563.610909727235;1889.2915156816193;1967.1541237267968;2027.290448343083;2032.1261857401312;2116.5661039486868;82147.58926205331;83264.58367708183;84002.57998709967;84460.99999999939;84684;10380.896191037993;10641.680749577525;10651.09465695409;10911.072558832588;11083.334999900031;14612.196329201995;14709.411623535152;14782.92608536972;14788.334524946404;14801;8234.670613175525;8265.545395003212;8325.292350150185;8453.323734101257;8537.146285371413;8162.347012239006;8221.342292616577;8225.506469611842;8332.125126861714;8348.624311905978;5999.340072592011;6343.28917583219;6561.409473147344;6674.299198584175;6682.732690692415;1768.8231176882466;1917.664408728467;1938.0987840654163;1951.3658061129906;1971.063744721246;875.8160786234835;934.6168071090821;1034.3690348887174;1040.7398150462384;1065.5577935156978;19840.50398740014;20310.18759249643;20381.490462738253;20424.285150019678;20790.688139677917;1005.8692369992001;1007.9344842585208;1009.8687170667913;1022.4580972084807;1025.1491262252312;10184.236182286359;11698.70753231176;11750.471228794553;11901.523939042514;11913.166078374605;501907;504212;505373;509809;510255;99018.504907475;106433.46783266035;106435;106889;107276.46361768297;1019.8470229465491;1030.6598822388744;1059.6556119261118;1063.3991794636006;1066.440118937564;17727.379541715978;18008.819911800714;18052.458426247234;18658.627281666028;18799.342023029127;34588.654113459546;42617.36073958955;43382.13235735331;45648.31527527023;45838.31242531431;61227.69386153041;63278.683606581675;63965.74440052857;64403;64967.35032649613;8370.414071014971;8440.915590844013;8459;8525.573721313785;8953.418027828171;10392.740181495523;13061.412236449572;13167.736645267232;13670.589882303548;13712.245826479639;11364.374959377314;11478.713032173928;11500;11556.768864622492;11868.465919033837;11337.489812958273;11624.825627615422;11668.766624667629;11743;11912.166148369532;11589.362585057903;11806.586769463027;11884.227525210834;11893.940530297294;11948.522059117886;8628.568571571272;8695.173958473935;8762.07998160184;8862.20240178387;8906;8575.699850505202;11001.669949901516;11516.654500365003;11923.821142682693;12135.817962730744 04:51:25 INFO - PROCESS | 26869 | |3;mootools.html;1250.312328219479;1349.203969657903;1353.7495563320786;1389.2775756606566;1790.6687262856365;8653.870191947119;8961.462312261261;8971.461712297267;9238.214751746109;9243.53782310885;9063.637454501828;9273.72178834636;9276.907230927689;9279.628814847398;9281.02549232331;2100.863443876151;2176.706144670473;2181.5091604389045;2397.61638137898;2422.842515236504;2192.693022976777;2333.6499525071204;2417.540667273221;2422.2127808462233;2443.2425947956103;2384.3919800450863;2424.7090349158125;2430.0765709030543;2438.743931887153;2441.633754936765;1997.4307322413188;2191.638379667353;2199.593075281078;2199.8570092943905;2202.394341556069;2299.6320588705894;2356.634721618135;2357.1278626907983;2377.714674239085;2382.452036031705;9178.128077832594;9211.861822072675;9212.907870921272;9220.53897305132;9224;5399.325084364455;5570.053090974544;5570.275864137677;5571.387147413781;5572.637778544382;3539.5752509698746;3598.964010359889;3630.2739452109686;3687.797171155585;3697.334479793663;1103.9558417663284;1108.130117857478;1120.9831852522218;1132.2131118872387;1136.386351370259;12823.743525129445;12832.165909215964;12833.67915802103;12855.742885142245;12862.614121576367;4822.68652537584;4896.804127834846;4951.925721114186;5023.648344615842;5036.622253331015;10810.243282970274;10839.729006774736;10843.728906777395;10849.457527123612;10854.240203185858;5595.440455954454;5598.300212473441;5600.187972744011;5601.60788744784;5617.550595952314;5053.9747301264;5131.204663277198;5134.460881607452;5148.819791307286;5160.793568257228;3989.2420440116284;4036.798160091984;4049.1294371710223;4090.7136500444685;4130.091379896418;1288.9033322500663;1327.508821735964;1341.637757805387;1343.2007955266602;1365.0308281120317;994.4083270454068;999.4852650884654;1005.3867141043958;1007.7430255285002;1023.5240613114937;5961.910571341434;6276.592021518587;6316.210473690789;6368.076628888785;6420.582662126947;459.23995786972614;469.0782612167108;473.3396911308705;473.34205454418384;474.26015415951224;4055.533613634411;4145.751254924714;4148.439960605279;4163.500379954365;4190.643795277375;11002.889971100347;11433.085353171726;11483.770324593628;11490.540378384938;11511.424428778528;11328.71678208035;11340.376279304552;11341.376224307742;11362.375069371265;11363.602273920373;8362.958185209034;8427.578621068922;8679.088695686989;8691.391602587759;8706.1729135732;6508.967455162694;6626.27111017778;6726.058351830649;6774.356436138559;6817.965910170417;10429.895701042893;10833.945830270799;10854.131669466582;10937.125029997422;10954.342739435659;9738.951305243429;10410.68767936963;10851.45742712877;10872.61945831892;10883.564657413615;7982.560959147303;8271.090180080308;8369.163083691583;8381.245687887875;8385.748427547183;8633.654653813777;8708.956455217683;8718.956405217934;8730.781730456536;8737.956310218407;7531.209223031722;7814.57019863913;7858.764237072897;7975.122736499096;8009.919900800917;7762.796766501201;7768.922310777046;7854.3323817473865;7888.171741967011;7937.166597507178;7302.415806735342;7305.926940730737;7360.595167265852;7363.852722945404;7369.889451658337;5463.31708536433;5572.108462645957;5663.575231857545;5689.431056894277;5799.536037117105;5538.861528461821;5656.745446454837;5686.829395118153;5710.743016564182;6007.939920600913 04:51:25 INFO - PROCESS | 26869 | |4;prototype.html;326.6090297790586;337.90538649178234;339.6314998226924;342.4657534246577;345.38004282313256;5301.867453313669;5311.89376212476;5338.919916201256;5357.357117145938;5376.919346209805;5045.77294021769;5185;5201.427842937273;5214.374275086985;5273.419923808378;4065.6544193743566;4100.40544121103;4224.8521301754445;4246.57534246576;4258.169656916903;4151.294279972397;4193.056562273495;4209.831606735727;4227.598378154071;4261.808218630169;4090.7750073745933;4164.354525048622;4165.875023749277;4170.228507726065;4209.473815773028;4150.149219410044;4156.607536475284;4161.313383291753;4194.433751443562;4201.789910504478;3733.9439908401123;3736.159364143073;3742;3751.5498140223076;3752.45589389537;5003.2995380646735;5185.222216667492;5191.558717509016;5203.505666961632;5213.687178769286;4756.952430475685;4793.3049707792525;4806.158922188638;4807.591354734852;4845.539673731025;5513.365962914236;5540.722963851791;5567.3040869891265;5572.024895643246;5599.692016939067;3798.525184351956;4005.439238506611;4102.856400025985;4120.505539335269;4129.483814523184;5693.146028095777;5711.457906365258;5738.196652468658;5746.787486817563;5769.509591684669;4053.3919912012843;4055.5944405559208;4068.7558746475306;4080.3471444568727;4083.5303940047274;3969.186316805106;4143.8964025898995;4172.248995180836;4187.5184353799095;4190.434291370626;4688.484266730657;4705.411823522059;4727.747147006046;4774.618030557547;4812.302216178635;5548.087254191581;5566.638168519034;5569.66582005081;5571.387147413781;5620.100783874561;1319.2348437905991;1359.660734176835;1429.6497358147321;1443.7473442147582;1445.4651778841896;956.8086382723483;983.1593987140892;983.6409710455775;988.5946761827616;990.1435258501344;5256.710880901587;5268.631195816256;5281.788728450896;5291.629585928948;5292.708901010405;5601.663900165921;5673.858153546194;5681.289838770153;5688.573356998241;5698.037020749187;5093.541581257705;5362.651427657268;5367.3827509836865;5370.704611246419;5383.892322153535;2997.7451916586897;3019.7131272529073;3030.4848175810166;3032.742216911543;3032.7725420593542;5728.427157284238;5878.882422351529;5881.558883083786;5899.115132730039;5914.349421563625;5821.912671309934;5828.921649494857;5829.329627092854;5861.3845546217035;5863.853403664943;5890.617109887844;5909.940900591025;5913.822585322448;5924.911126333109;5925.200097986803;5397.757100930389;5638.887222255614;5708.885822283614;5721.771129154788;5764.221830052889;5653.547716182778;5672.9221447924765;5694.8928896308635;5700.574856285929;5704.833817231311;5740.712964351849;5895.174675545512;5918.7928422505;5924.919042098171;5928;3878.4570160178164;3996.2207369562657;4004.9199016020098;4007.8797636070963;4012.4583181270154;5577.466196795751;5693.743781529758;5694.886102277848;5719.428057194414;5719.828405147852;5491.75287112073;5523.171524271392;5523.7238138093735;5551.195076713934;5589.161625756169;5307.761150748149;5538.369274594535;5572.86067848307;5574.9721251393485;5600.159976003632;5430.83707488776;5458.344998600193;5460.34475862899;5490.725463726878;5494.2582751329655;5520.3990842655185;5547.6408279971665;5567.638103523258;5582.218489411404;5584.4136365681015;5204.375474943031;5461.371942226695;5465.344158700822;5480.3971563128825;5482.17767334903 04:51:25 INFO - PROCESS | 26869 | |5;yui.html;750.4034292737276;762.7559181062059;793.9603019849006;853.4452605806222;863.7711006583253;16164.272607732652;16660.58348541287;16674;16688.666226675457;16712.91643541782;14552.272386380691;15583.688326233498;15665;15699.921500392482;15708.607284817885;662.3343539742552;792.6551949901782;809.34038758412;823.4112609484217;827.9710210142646;818.9754307370788;828.6809578312333;830.9293710034655;831.2020460358063;831.3058596072257;883.1874675298741;886.5345693510894;887.7026196224273;888.773362792487;889.0531583863165;689.2762599270745;838.9370797190174;862.5169904853302;881.0132651430375;882.668999125328;625.9155014073087;626.2516293029831;626.3423405424286;626.8746250749823;626.9529785266124;15404.46084387041;15487.225638718131;15491.845081549265;15515.844841551552;15525;14283;14724.33740481681;14755.26223688822;14808.481703140338;14956.102633841896;12760;12867.48530058806;12964.87035129646;13121;13224.603261902159;4401.207782599131;4520.276755719069;4580.862574122782;4684.625229981593;4686.968866849288;76866;76899.23100769032;77622.61188694077;77927;78162.60918695427;11054.060404865515;11115.388653624128;11377.715557111138;11385;11564.537418503334;21633;21650.783492164875;21668.45828854291;21670.13319467235;21711.674324885138;7004.894926576106;8063.798405039921;8208.425410221227;8671.176238257356;8684.131586841266;4726.125666751662;4733.5503127202865;4779.808807647655;4797.4482934462985;4910.435299940481;3537.7169826413824;3648.562172539312;3656.963430365662;3657.341678497896;3665.7983810890123;643.5945354426681;650.7397041183565;651.6513665189101;656.2485953583121;659.4427708586237;632.2886752403546;661.4736052286037;670.6143967218771;681.3152781454705;684.0560027162584;9139.725808225763;9410.576524056298;9492.335536512377;9609.855852162224;9925.156361709336;583.0700033446645;631.9684015799191;647.7117682631279;686.6017709728445;686.6360828760764;4535.297028960516;4536;4551.795169217393;4811.133995880705;5183.81856635016;120062;120354.57517973833;122578;126152;128617;107158;110737;111941;113139;114577;98166;98576;100715.49642251742;100855;102882;10367.740806479898;10534.209934254808;10728.570857165625;10787.406692631981;10789.244752867224;56007;56376.43623563712;56587.43412565822;56917.43082569287;58366.83266334735;81705.59147204226;83815.580922095;83856.58071709602;86440.5677971606;87416.562917185;13608.183508989492;13739.862601373858;13826.723465530835;13900.860991390362;13996.16023038621;15467;15615.687686246438;15704.685906282039;15772.132532710351;15958.281877315781;14508.492202773274;14674.309223994118;14768.18774967387;14986.625334366729;15518.84481155174;13761;14355.210463424612;14947.701045978802;14989.550313490612;15580.766288505909;14468.85531144675;14489.347979340744;14740.705185896437;14788.11271323724;15401.229938503255;11509.550826251965;11893.76212475763;11912.880871191177;12584.18202816814;12922.806157907828;11259.887401125883;11554.48004839801;11642.5342986283;12043.156979011384;12175.817362739388 04:51:25 INFO - PROCESS | 26869 | __end_tp_report 04:51:25 INFO - PROCESS | 26869 | __start_cc_report 04:51:25 INFO - PROCESS | 26869 | _x_x_mozilla_cycle_collect,847 04:51:25 INFO - PROCESS | 26869 | __end_cc_report 04:51:25 INFO - PROCESS | 26869 | __startTimestamp1468669885675__endTimestamp 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | ------- Summary: start ------- 04:51:25 INFO - PROCESS | 26869 | Number of tests: 6 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | [#0] dojo.html Cycles:165 Average:5382.44 Median:5630.10 stddev:623.05 (11.1%) stddev-sans-first:597.30 04:51:25 INFO - PROCESS | 26869 | Values: 3042.3 3069.0 3092.6 3111.1 3127.1 5314.7 5321.9 5356.5 5435.9 5474.2 5416.1 5419.5 5453.2 5478.1 5510.5 4838.1 4864.5 4900.0 4915.0 5087.3 4987.2 4993.7 5036.4 5049.3 5063.1 4800.4 4826.6 4882.3 4927.6 4937.5 4913.2 4952.1 4967.4 5016.2 5040.7 4892.9 4909.3 4934.2 4934.2 4979.9 5295.6 5297.4 5308.5 5363.0 5388.8 5741.4 5750.1 5775.2 5781.1 5796.3 5727.1 5786.3 5810.4 5827.8 5834.9 5337.6 5446.7 5456.7 5465.6 5527.3 5942.7 5956.8 5958.8 5959.4 5963.0 5468.2 5638.3 5671.5 5684.0 5684.8 5811.1 5821.9 5822.2 5874.3 5913.0 5783.2 5838.0 5841.1 5844.3 5885.4 5568.2 5668.6 5681.5 5850.9 5859.5 4375.0 4383.1 4386.1 4422.4 4426.0 3903.7 3913.8 3914.5 3934.2 3950.3 5329.9 5333.4 5389.2 5415.0 5465.0 5839.6 5851.6 5860.5 5871.4 5882.4 5346.9 5357.4 5366.2 5371.1 5371.9 4918.8 4951.8 4970.2 5019.0 5036.5 6004.4 6015.5 6020.6 6022.4 6023.4 5835.6 5843.5 5852.1 5853.9 5857.8 5795.2 5927.8 5980.2 5993.7 6074.5 5712.3 5758.2 5760.5 5773.1 5776.2 5775.2 5785.2 5790.2 5799.2 5807.7 5506.4 5658.3 5711.8 5722.4 5729.7 5712.7 5713.0 5715.9 5718.1 5722.9 5538.7 5637.2 5649.8 5651.6 5655.3 5655.2 5689.7 5702.4 5724.3 5782.1 5618.4 5620.8 5629.0 5630.1 5631.0 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | [#1] ext.html Cycles:180 Average:59310.29 Median:43041.07 stddev:112158.91 (260.6%) stddev-sans-first:112386.95 04:51:25 INFO - PROCESS | 26869 | Values: 609.6 640.1 641.9 645.6 646.8 43471.8 45477.0 45617.5 49160.5 49191.6 46725.5 46838.3 47255.3 47258.1 47350.5 8979.4 9044.1 9104.3 9107.2 9129.2 8965.5 9015.4 9061.5 9061.7 9063.6 9138.4 9162.4 9163.1 9173.9 9185.7 9026.8 9048.4 9069.9 9074.4 9085.3 9027.5 9106.8 9109.5 9113.8 9114.0 43744.8 44216.3 44230.6 44258.3 44268.8 3422.9 3887.8 4047.6 4064.8 4089.7 32904.8 33845.3 34195.3 34267.0 34326.7 4339.6 4539.6 4548.4 4623.1 4635.6 355976.0 359934.0 360384.0 362195.0 364553.0 60932.4 61144.4 61181.0 61286.4 61475.4 64355.7 64402.0 64475.7 64505.4 64595.4 16436.4 16698.3 16706.4 16723.7 16731.7 13422.3 13465.9 13475.3 13496.1 13497.6 16127.0 17918.9 17925.7 18111.5 18142.0 5183.3 5918.4 5959.7 6058.7 6090.2 1030.0 1071.2 1085.7 1095.0 1192.8 32940.5 32974.0 33185.7 33568.0 34100.3 1079.3 1086.5 1094.1 1135.8 1150.7 15545.5 15897.8 15901.2 15957.4 16229.0 495080.0 496362.0 500654.0 502113.0 502365.0 60309.7 60426.0 60474.7 60583.4 60759.7 1240.1 1307.9 1342.6 1365.6 1390.3 32898.7 33940.0 33952.3 33990.8 34026.8 51520.5 52417.0 52495.7 52513.2 52626.5 394687.0 401803.0 403492.0 403569.0 404711.0 24817.9 27478.5 28100.9 28445.9 30243.2 48882.8 49705.0 49826.5 50310.7 51209.5 41684.2 42223.4 42242.6 43127.1 43861.3 42245.6 42964.8 43019.6 43062.6 43438.8 38188.6 40665.8 41718.0 41737.8 41837.0 35929.5 36452.3 36456.5 36586.5 37186.0 27685.9 27807.6 27909.9 28195.7 28262.3 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | [#2] jquery.html Cycles:180 Average:30364.27 Median:11913.96 stddev:83746.26 (702.9%) stddev-sans-first:83950.95 04:51:25 INFO - PROCESS | 26869 | Values: 392.7 392.8 392.8 399.8 421.6 13336.9 19010.3 20727.5 20728.2 21711.3 15401.9 19533.1 19537.4 19797.6 20146.9 5896.2 6010.5 6017.2 6018.1 6044.7 5982.4 6011.9 6021.4 6027.9 6044.7 4250.7 5105.6 6709.7 6720.4 6720.7 6600.3 6607.8 6696.7 6712.0 6724.3 5748.5 5837.9 5841.8 5867.6 5876.7 19150.9 23926.0 24633.6 24667.9 24969.1 2370.4 2431.6 2509.6 2746.8 3122.2 14860.3 14969.4 15173.4 15409.5 15563.6 1889.3 1967.2 2027.3 2032.1 2116.6 82147.6 83264.6 84002.6 84461.0 84684.0 10380.9 10641.7 10651.1 10911.1 11083.3 14612.2 14709.4 14782.9 14788.3 14801.0 8234.7 8265.5 8325.3 8453.3 8537.1 8162.3 8221.3 8225.5 8332.1 8348.6 5999.3 6343.3 6561.4 6674.3 6682.7 1768.8 1917.7 1938.1 1951.4 1971.1 875.8 934.6 1034.4 1040.7 1065.6 19840.5 20310.2 20381.5 20424.3 20790.7 1005.9 1007.9 1009.9 1022.5 1025.1 10184.2 11698.7 11750.5 11901.5 11913.2 501907.0 504212.0 505373.0 509809.0 510255.0 99018.5 106433.5 106435.0 106889.0 107276.5 1019.8 1030.7 1059.7 1063.4 1066.4 17727.4 18008.8 18052.5 18658.6 18799.3 34588.7 42617.4 43382.1 45648.3 45838.3 61227.7 63278.7 63965.7 64403.0 64967.4 8370.4 8440.9 8459.0 8525.6 8953.4 10392.7 13061.4 13167.7 13670.6 13712.2 11364.4 11478.7 11500.0 11556.8 11868.5 11337.5 11624.8 11668.8 11743.0 11912.2 11589.4 11806.6 11884.2 11893.9 11948.5 8628.6 8695.2 8762.1 8862.2 8906.0 8575.7 11001.7 11516.7 11923.8 12135.8 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | [#3] mootools.html Cycles:180 Average:6057.58 Median:473.80 stddev:3488.01 (736.2%) stddev-sans-first:3479.08 04:51:25 INFO - PROCESS | 26869 | Values: 1250.3 1349.2 1353.7 1389.3 1790.7 8653.9 8961.5 8971.5 9238.2 9243.5 9063.6 9273.7 9276.9 9279.6 9281.0 2100.9 2176.7 2181.5 2397.6 2422.8 2192.7 2333.6 2417.5 2422.2 2443.2 2384.4 2424.7 2430.1 2438.7 2441.6 1997.4 2191.6 2199.6 2199.9 2202.4 2299.6 2356.6 2357.1 2377.7 2382.5 9178.1 9211.9 9212.9 9220.5 9224.0 5399.3 5570.1 5570.3 5571.4 5572.6 3539.6 3599.0 3630.3 3687.8 3697.3 1104.0 1108.1 1121.0 1132.2 1136.4 12823.7 12832.2 12833.7 12855.7 12862.6 4822.7 4896.8 4951.9 5023.6 5036.6 10810.2 10839.7 10843.7 10849.5 10854.2 5595.4 5598.3 5600.2 5601.6 5617.6 5054.0 5131.2 5134.5 5148.8 5160.8 3989.2 4036.8 4049.1 4090.7 4130.1 1288.9 1327.5 1341.6 1343.2 1365.0 994.4 999.5 1005.4 1007.7 1023.5 5961.9 6276.6 6316.2 6368.1 6420.6 459.2 469.1 473.3 473.3 474.3 4055.5 4145.8 4148.4 4163.5 4190.6 11002.9 11433.1 11483.8 11490.5 11511.4 11328.7 11340.4 11341.4 11362.4 11363.6 8363.0 8427.6 8679.1 8691.4 8706.2 6509.0 6626.3 6726.1 6774.4 6818.0 10429.9 10833.9 10854.1 10937.1 10954.3 9739.0 10410.7 10851.5 10872.6 10883.6 7982.6 8271.1 8369.2 8381.2 8385.7 8633.7 8709.0 8719.0 8730.8 8738.0 7531.2 7814.6 7858.8 7975.1 8009.9 7762.8 7768.9 7854.3 7888.2 7937.2 7302.4 7305.9 7360.6 7363.9 7369.9 5463.3 5572.1 5663.6 5689.4 5799.5 5538.9 5656.7 5686.8 5710.7 6007.9 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | [#4] prototype.html Cycles:180 Average:4688.32 Median:5348.14 stddev:1368.14 (25.6%) stddev-sans-first:1332.24 04:51:25 INFO - PROCESS | 26869 | Values: 326.6 337.9 339.6 342.5 345.4 5301.9 5311.9 5338.9 5357.4 5376.9 5045.8 5185.0 5201.4 5214.4 5273.4 4065.7 4100.4 4224.9 4246.6 4258.2 4151.3 4193.1 4209.8 4227.6 4261.8 4090.8 4164.4 4165.9 4170.2 4209.5 4150.1 4156.6 4161.3 4194.4 4201.8 3733.9 3736.2 3742.0 3751.5 3752.5 5003.3 5185.2 5191.6 5203.5 5213.7 4757.0 4793.3 4806.2 4807.6 4845.5 5513.4 5540.7 5567.3 5572.0 5599.7 3798.5 4005.4 4102.9 4120.5 4129.5 5693.1 5711.5 5738.2 5746.8 5769.5 4053.4 4055.6 4068.8 4080.3 4083.5 3969.2 4143.9 4172.2 4187.5 4190.4 4688.5 4705.4 4727.7 4774.6 4812.3 5548.1 5566.6 5569.7 5571.4 5620.1 1319.2 1359.7 1429.6 1443.7 1445.5 956.8 983.2 983.6 988.6 990.1 5256.7 5268.6 5281.8 5291.6 5292.7 5601.7 5673.9 5681.3 5688.6 5698.0 5093.5 5362.7 5367.4 5370.7 5383.9 2997.7 3019.7 3030.5 3032.7 3032.8 5728.4 5878.9 5881.6 5899.1 5914.3 5821.9 5828.9 5829.3 5861.4 5863.9 5890.6 5909.9 5913.8 5924.9 5925.2 5397.8 5638.9 5708.9 5721.8 5764.2 5653.5 5672.9 5694.9 5700.6 5704.8 5740.7 5895.2 5918.8 5924.9 5928.0 3878.5 3996.2 4004.9 4007.9 4012.5 5577.5 5693.7 5694.9 5719.4 5719.8 5491.8 5523.2 5523.7 5551.2 5589.2 5307.8 5538.4 5572.9 5575.0 5600.2 5430.8 5458.3 5460.3 5490.7 5494.3 5520.4 5547.6 5567.6 5582.2 5584.4 5204.4 5461.4 5465.3 5480.4 5482.2 04:51:25 INFO - PROCESS | 26869 | 04:51:25 INFO - PROCESS | 26869 | [#5] yui.html Cycles:180 Average:22583.49 Median:3657.15 stddev:33127.07 (905.8%) stddev-sans-first:33179.44 04:51:25 INFO - PROCESS | 26869 | Values: 750.4 762.8 794.0 853.4 863.8 16164.3 16660.6 16674.0 16688.7 16712.9 14552.3 15583.7 15665.0 15699.9 15708.6 662.3 792.7 809.3 823.4 828.0 819.0 828.7 830.9 831.2 831.3 883.2 886.5 887.7 888.8 889.1 689.3 838.9 862.5 881.0 882.7 625.9 626.3 626.3 626.9 627.0 15404.5 15487.2 15491.8 15515.8 15525.0 14283.0 14724.3 14755.3 14808.5 14956.1 12760.0 12867.5 12964.9 13121.0 13224.6 4401.2 4520.3 4580.9 4684.6 4687.0 76866.0 76899.2 77622.6 77927.0 78162.6 11054.1 11115.4 11377.7 11385.0 11564.5 21633.0 21650.8 21668.5 21670.1 21711.7 7004.9 8063.8 8208.4 8671.2 8684.1 4726.1 4733.6 4779.8 4797.4 4910.4 3537.7 3648.6 3657.0 3657.3 3665.8 643.6 650.7 651.7 656.2 659.4 632.3 661.5 670.6 681.3 684.1 9139.7 9410.6 9492.3 9609.9 9925.2 583.1 632.0 647.7 686.6 686.6 4535.3 4536.0 4551.8 4811.1 5183.8 120062.0 120354.6 122578.0 126152.0 128617.0 107158.0 110737.0 111941.0 113139.0 114577.0 98166.0 98576.0 100715.5 100855.0 102882.0 10367.7 10534.2 10728.6 10787.4 10789.2 56007.0 56376.4 56587.4 56917.4 58366.8 81705.6 83815.6 83856.6 86440.6 87416.6 13608.2 13739.9 13826.7 13900.9 13996.2 15467.0 15615.7 15704.7 15772.1 15958.3 14508.5 14674.3 14768.2 14986.6 15518.8 13761.0 14355.2 14947.7 14989.6 15580.8 14468.9 14489.3 14740.7 14788.1 15401.2 11509.6 11893.8 11912.9 12584.2 12922.8 11259.9 11554.5 11642.5 12043.2 12175.8 04:51:25 INFO - PROCESS | 26869 | -------- Summary: end -------- 04:51:25 INFO - PROCESS | 26869 | 04:51:26 INFO - TEST-INFO | 26869: exit 0 04:51:26 INFO - TEST-OK | dromaeo_css | took 1114325ms 04:51:26 INFO - TEST-START | kraken 04:51:26 INFO - operating with platform_type : linux_ 04:51:26 INFO - Initialising browser for kraken test... 04:51:26 INFO - TEST-INFO | started process 27059 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplVOa63/profile http://localhost:39567/getInfo.html) 04:51:30 INFO - PROCESS | 27059 | __metrics Screen width/height:1600/1200 04:51:30 INFO - PROCESS | 27059 | colorDepth:24 04:51:30 INFO - PROCESS | 27059 | Browser inner width/height: 1024/697 04:51:30 INFO - PROCESS | 27059 | __metrics 04:51:31 INFO - PROCESS | 27059 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:51:31 INFO - PROCESS | 27059 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:51:32 INFO - TEST-INFO | 27059: exit 0 04:51:32 INFO - Browser initialized. 04:51:32 INFO - Running cycle 1/1 for kraken test... 04:51:32 INFO - TEST-INFO | started process 27143 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplVOa63/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:51:44 INFO - PROCESS | 27143 | RSS: Main: 157204480 04:51:44 INFO - PROCESS | 27143 | 04:51:46 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39567/tests/kraken/driver.html?testName=audio-beat-detection) 04:51:47 INFO - PROCESS | 27143 | RSS: Main: 216641536 04:51:47 INFO - PROCESS | 27143 | 04:51:49 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39567/tests/kraken/driver.html?testName=audio-dft) 04:51:50 INFO - PROCESS | 27143 | RSS: Main: 204800000 04:51:50 INFO - PROCESS | 27143 | 04:51:53 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39567/tests/kraken/driver.html?testName=audio-fft) 04:51:54 INFO - PROCESS | 27143 | RSS: Main: 269262848 04:51:54 INFO - PROCESS | 27143 | 04:51:56 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39567/tests/kraken/driver.html?testName=audio-oscillator) 04:51:56 INFO - PROCESS | 27143 | RSS: Main: 180625408 04:51:56 INFO - PROCESS | 27143 | 04:51:58 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39567/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:51:59 INFO - PROCESS | 27143 | RSS: Main: 184840192 04:51:59 INFO - PROCESS | 27143 | 04:52:02 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39567/tests/kraken/driver.html?testName=imaging-darkroom) 04:52:03 INFO - PROCESS | 27143 | RSS: Main: 221040640 04:52:03 INFO - PROCESS | 27143 | 04:52:06 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39567/tests/kraken/driver.html?testName=imaging-desaturate) 04:52:07 INFO - PROCESS | 27143 | RSS: Main: 221683712 04:52:07 INFO - PROCESS | 27143 | 04:52:09 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39567/tests/kraken/driver.html?testName=json-parse-financial) 04:52:10 INFO - PROCESS | 27143 | RSS: Main: 221392896 04:52:10 INFO - PROCESS | 27143 | 04:52:11 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39567/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:52:12 INFO - PROCESS | 27143 | RSS: Main: 210055168 04:52:12 INFO - PROCESS | 27143 | 04:52:14 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:52:14 INFO - PROCESS | 27143 | RSS: Main: 213274624 04:52:14 INFO - PROCESS | 27143 | 04:52:16 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:52:17 INFO - PROCESS | 27143 | RSS: Main: 212140032 04:52:17 INFO - PROCESS | 27143 | 04:52:20 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:52:20 INFO - PROCESS | 27143 | RSS: Main: 230727680 04:52:20 INFO - PROCESS | 27143 | 04:52:23 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:52:24 INFO - PROCESS | 27143 | RSS: Main: 224550912 04:52:24 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | Cycle 1(1): loaded http://localhost:39567/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39567/tests/kraken/driver.html?testName=ai-astar) 04:52:25 INFO - PROCESS | 27143 | RSS: Main: 267390976 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | __start_tp_report 04:52:25 INFO - PROCESS | 27143 | _x_x_mozilla_page_load 04:52:25 INFO - PROCESS | 27143 | _x_x_mozilla_page_load_details 04:52:25 INFO - PROCESS | 27143 | |i|pagename|runs| 04:52:25 INFO - PROCESS | 27143 | |0;ai-astar;101;110;111;111;110;111;110;110;111;109 04:52:25 INFO - PROCESS | 27143 | |1;audio-beat-detection;144;116;118;187;116;121;148;119;148;117 04:52:25 INFO - PROCESS | 27143 | |2;audio-dft;160;159;159;159;160;162;162;160;160;160 04:52:25 INFO - PROCESS | 27143 | |3;audio-fft;73;75;76;110;75;77;74;75;100;74 04:52:25 INFO - PROCESS | 27143 | |4;audio-oscillator;83;82;120;82;82;84;128;82;83;132 04:52:25 INFO - PROCESS | 27143 | |5;imaging-gaussian-blur;117;117;117;116;117;117;117;116;117;116 04:52:25 INFO - PROCESS | 27143 | |6;imaging-darkroom;146;145;145;146;146;145;146;145;146;146 04:52:25 INFO - PROCESS | 27143 | |7;imaging-desaturate;95;95;95;95;94;95;94;94;95;95 04:52:25 INFO - PROCESS | 27143 | |8;json-parse-financial;54;49;51;51;51;51;53;50;49;50 04:52:25 INFO - PROCESS | 27143 | |9;json-stringify-tinderbox;62;63;62;61;62;61;62;62;62;63 04:52:25 INFO - PROCESS | 27143 | |10;stanford-crypto-aes;76;75;83;79;75;78;79;84;81;75 04:52:25 INFO - PROCESS | 27143 | |11;stanford-crypto-ccm;120;127;129;132;119;126;122;126;155;126 04:52:25 INFO - PROCESS | 27143 | |12;stanford-crypto-pbkdf2;156;155;160;157;155;156;155;158;157;157 04:52:25 INFO - PROCESS | 27143 | |13;stanford-crypto-sha256-iterative;63;63;62;63;65;61;62;62;62;66 04:52:25 INFO - PROCESS | 27143 | __end_tp_report 04:52:25 INFO - PROCESS | 27143 | __start_cc_report 04:52:25 INFO - PROCESS | 27143 | _x_x_mozilla_cycle_collect,1633 04:52:25 INFO - PROCESS | 27143 | __end_cc_report 04:52:25 INFO - PROCESS | 27143 | __startTimestamp1468669945924__endTimestamp 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | ------- Summary: start ------- 04:52:25 INFO - PROCESS | 27143 | Number of tests: 14 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#0] ai-astar Cycles:10 Average:109.40 Median:110.50 stddev:3.03 (2.7%) stddev-sans-first:0.71 04:52:25 INFO - PROCESS | 27143 | Values: 101.0 110.0 111.0 111.0 110.0 111.0 110.0 110.0 111.0 109.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#1] audio-beat-detection Cycles:10 Average:133.40 Median:132.50 stddev:23.30 (17.6%) stddev-sans-first:24.39 04:52:25 INFO - PROCESS | 27143 | Values: 144.0 116.0 118.0 187.0 116.0 121.0 148.0 119.0 148.0 117.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#2] audio-dft Cycles:10 Average:160.10 Median:160.00 stddev:1.10 (0.7%) stddev-sans-first:1.17 04:52:25 INFO - PROCESS | 27143 | Values: 160.0 159.0 159.0 159.0 160.0 162.0 162.0 160.0 160.0 160.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#3] audio-fft Cycles:10 Average:80.90 Median:75.00 stddev:12.97 (17.3%) stddev-sans-first:13.43 04:52:25 INFO - PROCESS | 27143 | Values: 73.0 75.0 76.0 110.0 75.0 77.0 74.0 75.0 100.0 74.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:82.00 stddev:21.50 (26.2%) stddev-sans-first:22.30 04:52:25 INFO - PROCESS | 27143 | Values: 83.0 82.0 120.0 82.0 82.0 84.0 128.0 82.0 83.0 132.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 04:52:25 INFO - PROCESS | 27143 | Values: 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 04:52:25 INFO - PROCESS | 27143 | Values: 146.0 145.0 145.0 146.0 146.0 145.0 146.0 145.0 146.0 146.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 04:52:25 INFO - PROCESS | 27143 | Values: 95.0 95.0 95.0 95.0 94.0 95.0 94.0 94.0 95.0 95.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.00 stddev:1.60 (3.1%) stddev-sans-first:1.24 04:52:25 INFO - PROCESS | 27143 | Values: 54.0 49.0 51.0 51.0 51.0 51.0 53.0 50.0 49.0 50.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 04:52:25 INFO - PROCESS | 27143 | Values: 62.0 63.0 62.0 61.0 62.0 61.0 62.0 62.0 62.0 63.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#10] stanford-crypto-aes Cycles:10 Average:78.50 Median:79.00 stddev:3.34 (4.2%) stddev-sans-first:3.42 04:52:25 INFO - PROCESS | 27143 | Values: 76.0 75.0 83.0 79.0 75.0 78.0 79.0 84.0 81.0 75.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#11] stanford-crypto-ccm Cycles:10 Average:128.20 Median:126.50 stddev:10.22 (8.1%) stddev-sans-first:10.40 04:52:25 INFO - PROCESS | 27143 | Values: 120.0 127.0 129.0 132.0 119.0 126.0 122.0 126.0 155.0 126.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.60 Median:157.00 stddev:1.58 (1.0%) stddev-sans-first:1.66 04:52:25 INFO - PROCESS | 27143 | Values: 156.0 155.0 160.0 157.0 155.0 156.0 155.0 158.0 157.0 157.0 04:52:25 INFO - PROCESS | 27143 | 04:52:25 INFO - PROCESS | 27143 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:1.52 (2.4%) stddev-sans-first:1.62 04:52:25 INFO - PROCESS | 27143 | Values: 63.0 63.0 62.0 63.0 65.0 61.0 62.0 62.0 62.0 66.0 04:52:25 INFO - PROCESS | 27143 | -------- Summary: end -------- 04:52:25 INFO - PROCESS | 27143 | 04:52:26 INFO - TEST-INFO | 27143: exit 0 04:52:27 INFO - TEST-OK | kraken | took 60548ms 04:52:27 INFO - SUITE-END | took 1174s 04:52:27 INFO - Completed test suite (00:19:34) 04:52:27 INFO - javascript benchmark 04:52:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3042.285063010192, 3068.953965690515, 3092.5670406143136, 3111.1288839125054, 3127.061881435569, 5314.734263286835, 5321.920171197435, 5356.464353564642, 5435.864103397417, 5474.151506516491, 5416.1063424534905, 5419.512243898049, 5453.2092846537225, 5478.096114141172, 5510.504054635082, 4838.080764654722, 4864.464908860023, 4900.019996000797, 4915.041566894457, 5087.313212716292, 4987.152184128707, 4993.675411098267, 5036.445990940993, 5049.3435853339015, 5063.113955057868, 4800.399950006249, 4826.589739872115, 4882.340883980671, 4927.556519913189, 4937.481564435719, 4913.213885778258, 4952.059108769322, 4967.379077615298, 5016.247562865563, 5040.722760248222, 4892.877678058041, 4909.312696222496, 4934.210526315772, 4934.210526315772, 4979.850604481871, 5295.576353891679, 5297.364316282071, 5308.495692909168, 5362.973185134049, 5388.757505912244, 5741.397153298885, 5750.079987202028, 5775.16260142277, 5781.075027995501, 5796.275465566804, 5727.11229759388, 5786.334571524287, 5810.360860305363, 5827.825165245049, 5834.912476312858, 5337.626366154371, 5446.673199607996, 5456.699881506515, 5465.6447330923365, 5527.281453411111, 5942.673152976541, 5956.791512297049, 5958.76164953397, 5959.404059594006, 5963.0, 5468.179773033997, 5638.2670252866865, 5671.546276297886, 5683.9715801420725, 5684.8294551163535, 5811.128330750336, 5821.94178058214, 5822.184894114827, 5874.324452687995, 5912.970435147797, 5783.248177736868, 5838.036723940587, 5841.065429531254, 5844.298684157929, 5885.440883116183, 5568.248286481273, 5668.57485688575, 5681.517071049007, 5850.912236316459, 5859.501942334864, 4375.037491751809, 4383.101464199832, 4386.12277544486, 4422.447194100738, 4425.977870110629, 3903.746256493319, 3913.8238779255003, 3914.451976723261, 3934.15415685629, 3950.308695978192, 5329.946700532945, 5333.359996800331, 5389.164679474688, 5415.0, 5464.972675136599, 5839.591228614041, 5851.590388672837, 5860.472557469763, 5871.4128587141795, 5882.411758824083, 5346.946530534645, 5357.383900851374, 5366.195070739421, 5371.060064488699, 5371.892562148735, 4918.827841025547, 4951.801927922843, 4970.15507363749, 5019.046381187636, 5036.521530454601, 6004.369541198199, 6015.488683461867, 6020.638761674313, 6022.427869352405, 6023.367546407564, 5835.56233282497, 5843.474087332161, 5852.122181672783, 5853.8536536585225, 5857.824265271878, 5795.246617939641, 5927.792527261525, 5980.222571065734, 5993.70031498432, 6074.483668888194, 5712.257406537124, 5758.193852860687, 5760.510356619734, 5773.1051686988585, 5776.19133321327, 5775.162601422686, 5785.190073389645, 5790.1893734877995, 5799.159121927381, 5807.738651760596, 5506.449355064461, 5658.349289831557, 5711.800086997101, 5722.370539240597, 5729.742161602821, 5712.685802280915, 5713.028785106459, 5715.914261286167, 5718.142278658068, 5722.885542289214, 5538.667679939216, 5637.2107904894165, 5649.802256920988, 5651.632643878134, 5655.293088363955, 5655.179998900218, 5689.687067211343, 5702.429757024264, 5724.341700704473, 5782.132680098019, 5618.438156184349, 5620.831375058755, 5629.043062679436, 5630.099184130683, 5631.0427227372265], "value": 5339.265397115398, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.5550248318727, 640.1134428816089, 641.9229692436905, 645.5835985789163, 646.7800947677786, 43471.78264108679, 45476.999999999956, 45617.54382456175, 49160.50839491604, 49191.57275845173, 46725.53274467254, 46838.29742553864, 47255.29117063238, 47258.054838903205, 47350.526494735044, 8979.416337938046, 9044.09559044097, 9104.317176211778, 9107.180353768159, 9129.178373946343, 8965.462072275684, 9015.413998090136, 9061.546922653873, 9061.728148155533, 9063.637454501844, 9138.360314777969, 9162.35863489556, 9163.083691630816, 9173.862392064124, 9185.678501252423, 9026.774330641754, 9048.366614337, 9069.86395204066, 9074.410163339428, 9085.273797978429, 9027.503487308195, 9106.81786364269, 9109.544522773835, 9113.772155696095, 9113.997460279365, 43744.78127609374, 44216.3367549487, 44230.55769442297, 44258.33612495815, 44268.778656106835, 3422.9315413691834, 3887.826009377568, 4047.6154765297542, 4064.7764372959477, 4089.734167279117, 32904.83547582271, 33845.32309353799, 34195.31609367799, 34267.0, 34326.65673343284, 4339.566043395666, 4539.568740969603, 4548.408706868119, 4623.12160689468, 4635.605973492257, 355976.0, 359934.0, 360384.0, 362195.0, 364553.0, 60932.39067609356, 61144.38855611387, 61181.0, 61286.38713612896, 61475.38524614786, 64355.67822160859, 64402.0, 64475.67762161253, 64505.35494644994, 64595.35404645988, 16436.424725134802, 16698.332066717438, 16706.4152754653, 16723.749143762852, 16731.6653666926, 13422.261775602436, 13465.865341346656, 13475.326233688276, 13496.122752021283, 13497.595072147851, 16127.0, 17918.91040544815, 17925.7311140333, 18111.45665630033, 18142.0, 5183.2743415921805, 5918.378570250148, 5959.67221802805, 6058.727357268934, 6090.208272924491, 1030.0317701360846, 1071.1912506058031, 1085.6580177244143, 1094.950727217277, 1192.785298646252, 32940.50589241163, 32974.0, 33185.66814331874, 33568.0, 34100.31799363999, 1079.3092420850662, 1086.5273605981424, 1094.0809719835377, 1135.846660700795, 1150.7468356961456, 15545.533633990999, 15897.761533577006, 15901.204939753197, 15957.441489548042, 16229.0, 495080.0, 496362.0, 500654.0, 502113.0, 502365.0, 60309.69845150834, 60426.0, 60474.69762651159, 60583.39416605866, 60759.69620151871, 1240.1133189769178, 1307.8888294495075, 1342.5502456676927, 1365.590322903125, 1390.2840037380756, 32898.67101328956, 33940.0, 33952.320953581286, 33990.8300458506, 34026.829865851505, 51520.48479515157, 52417.0, 52495.73752131215, 52513.21230181627, 52626.47373526216, 394687.0, 401803.0, 403492.0, 403569.0, 404711.0, 24817.875910620332, 27478.450430991667, 28100.859495702392, 28445.857770711013, 30243.243918902204, 48882.75558622184, 49705.0, 49826.50173498219, 50310.74844625754, 51209.48790512047, 41684.166316674105, 42223.366649500895, 42242.57757422386, 43127.13745725131, 43861.342079869464, 42245.577544224165, 42964.78517607517, 43019.56980430156, 43062.56937430586, 43438.782806085765, 38188.618113818506, 40665.796671016455, 41718.0, 41737.79131104325, 41837.0, 35929.46105808362, 36452.27095458129, 36456.453153202194, 36586.45120323144, 37186.0, 27685.861570692017, 27807.582886257125, 27909.87868284229, 28195.718042820128, 28262.293442664097], "value": 20429.474620762703, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.6623104130449, 392.78789453695003, 392.79182033522244, 399.8420623853578, 421.57210431412125, 13336.866631333683, 19010.334638287663, 20727.481812954684, 20728.170873165072, 21711.34865954024, 15401.922990385032, 19533.12100955457, 19537.413877583695, 19797.60404791907, 20146.899265503653, 5896.204012458317, 6010.519158467312, 6017.247844019497, 6018.097285407202, 6044.7279872405625, 5982.401759824026, 6011.939880601203, 6021.397860213988, 6027.939720602782, 6044.727987240584, 4250.723702959298, 5105.591552675777, 6709.6980635871505, 6720.3951644352, 6720.731170753164, 6600.273969863335, 6607.834804129887, 6696.698648560802, 6711.966440167792, 6724.260331363546, 5748.4538968798315, 5837.9124313135335, 5841.795537156179, 5867.559933005042, 5876.7355469004, 19150.904245478683, 23926.0, 24633.63049554258, 24667.876660616763, 24969.126080587273, 2370.3718514593647, 2431.5866302728577, 2509.5859183234743, 2746.7665248453904, 3122.1570176052337, 14860.331285092088, 14969.401223951028, 15173.393064277416, 15409.536094071045, 15563.610909727235, 1889.2915156816193, 1967.1541237267968, 2027.290448343083, 2032.1261857401312, 2116.5661039486868, 82147.58926205331, 83264.58367708183, 84002.57998709967, 84460.99999999939, 84684.0, 10380.896191037993, 10641.680749577525, 10651.09465695409, 10911.072558832588, 11083.334999900031, 14612.196329201995, 14709.411623535152, 14782.92608536972, 14788.334524946404, 14801.0, 8234.670613175525, 8265.545395003212, 8325.292350150185, 8453.323734101257, 8537.146285371413, 8162.347012239006, 8221.342292616577, 8225.506469611842, 8332.125126861714, 8348.624311905978, 5999.340072592011, 6343.28917583219, 6561.409473147344, 6674.299198584175, 6682.732690692415, 1768.8231176882466, 1917.664408728467, 1938.0987840654163, 1951.3658061129906, 1971.063744721246, 875.8160786234835, 934.6168071090821, 1034.3690348887174, 1040.7398150462384, 1065.5577935156978, 19840.50398740014, 20310.18759249643, 20381.490462738253, 20424.285150019678, 20790.688139677917, 1005.8692369992001, 1007.9344842585208, 1009.8687170667913, 1022.4580972084807, 1025.1491262252312, 10184.236182286359, 11698.70753231176, 11750.471228794553, 11901.523939042514, 11913.166078374605, 501907.0, 504212.0, 505373.0, 509809.0, 510255.0, 99018.504907475, 106433.46783266035, 106435.0, 106889.0, 107276.46361768297, 1019.8470229465491, 1030.6598822388744, 1059.6556119261118, 1063.3991794636006, 1066.440118937564, 17727.379541715978, 18008.819911800714, 18052.458426247234, 18658.627281666028, 18799.342023029127, 34588.654113459546, 42617.36073958955, 43382.13235735331, 45648.31527527023, 45838.31242531431, 61227.69386153041, 63278.683606581675, 63965.74440052857, 64403.0, 64967.35032649613, 8370.414071014971, 8440.915590844013, 8459.0, 8525.573721313785, 8953.418027828171, 10392.740181495523, 13061.412236449572, 13167.736645267232, 13670.589882303548, 13712.245826479639, 11364.374959377314, 11478.713032173928, 11500.0, 11556.768864622492, 11868.465919033837, 11337.489812958273, 11624.825627615422, 11668.766624667629, 11743.0, 11912.166148369532, 11589.362585057903, 11806.586769463027, 11884.227525210834, 11893.940530297294, 11948.522059117886, 8628.568571571272, 8695.173958473935, 8762.07998160184, 8862.20240178387, 8906.0, 8575.699850505202, 11001.669949901516, 11516.654500365003, 11923.821142682693, 12135.817962730744], "value": 9650.595042916424, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1250.312328219479, 1349.203969657903, 1353.7495563320786, 1389.2775756606566, 1790.6687262856365, 8653.870191947119, 8961.462312261261, 8971.461712297267, 9238.214751746109, 9243.53782310885, 9063.637454501828, 9273.72178834636, 9276.907230927689, 9279.628814847398, 9281.02549232331, 2100.863443876151, 2176.706144670473, 2181.5091604389045, 2397.61638137898, 2422.842515236504, 2192.693022976777, 2333.6499525071204, 2417.540667273221, 2422.2127808462233, 2443.2425947956103, 2384.3919800450863, 2424.7090349158125, 2430.0765709030543, 2438.743931887153, 2441.633754936765, 1997.4307322413188, 2191.638379667353, 2199.593075281078, 2199.8570092943905, 2202.394341556069, 2299.6320588705894, 2356.634721618135, 2357.1278626907983, 2377.714674239085, 2382.452036031705, 9178.128077832594, 9211.861822072675, 9212.907870921272, 9220.53897305132, 9224.0, 5399.325084364455, 5570.053090974544, 5570.275864137677, 5571.387147413781, 5572.637778544382, 3539.5752509698746, 3598.964010359889, 3630.2739452109686, 3687.797171155585, 3697.334479793663, 1103.9558417663284, 1108.130117857478, 1120.9831852522218, 1132.2131118872387, 1136.386351370259, 12823.743525129445, 12832.165909215964, 12833.67915802103, 12855.742885142245, 12862.614121576367, 4822.68652537584, 4896.804127834846, 4951.925721114186, 5023.648344615842, 5036.622253331015, 10810.243282970274, 10839.729006774736, 10843.728906777395, 10849.457527123612, 10854.240203185858, 5595.440455954454, 5598.300212473441, 5600.187972744011, 5601.60788744784, 5617.550595952314, 5053.9747301264, 5131.204663277198, 5134.460881607452, 5148.819791307286, 5160.793568257228, 3989.2420440116284, 4036.798160091984, 4049.1294371710223, 4090.7136500444685, 4130.091379896418, 1288.9033322500663, 1327.508821735964, 1341.637757805387, 1343.2007955266602, 1365.0308281120317, 994.4083270454068, 999.4852650884654, 1005.3867141043958, 1007.7430255285002, 1023.5240613114937, 5961.910571341434, 6276.592021518587, 6316.210473690789, 6368.076628888785, 6420.582662126947, 459.23995786972614, 469.0782612167108, 473.3396911308705, 473.34205454418384, 474.26015415951224, 4055.533613634411, 4145.751254924714, 4148.439960605279, 4163.500379954365, 4190.643795277375, 11002.889971100347, 11433.085353171726, 11483.770324593628, 11490.540378384938, 11511.424428778528, 11328.71678208035, 11340.376279304552, 11341.376224307742, 11362.375069371265, 11363.602273920373, 8362.958185209034, 8427.578621068922, 8679.088695686989, 8691.391602587759, 8706.1729135732, 6508.967455162694, 6626.27111017778, 6726.058351830649, 6774.356436138559, 6817.965910170417, 10429.895701042893, 10833.945830270799, 10854.131669466582, 10937.125029997422, 10954.342739435659, 9738.951305243429, 10410.68767936963, 10851.45742712877, 10872.61945831892, 10883.564657413615, 7982.560959147303, 8271.090180080308, 8369.163083691583, 8381.245687887875, 8385.748427547183, 8633.654653813777, 8708.956455217683, 8718.956405217934, 8730.781730456536, 8737.956310218407, 7531.209223031722, 7814.57019863913, 7858.764237072897, 7975.122736499096, 8009.919900800917, 7762.796766501201, 7768.922310777046, 7854.3323817473865, 7888.171741967011, 7937.166597507178, 7302.415806735342, 7305.926940730737, 7360.595167265852, 7363.852722945404, 7369.889451658337, 5463.31708536433, 5572.108462645957, 5663.575231857545, 5689.431056894277, 5799.536037117105, 5538.861528461821, 5656.745446454837, 5686.829395118153, 5710.743016564182, 6007.939920600913], "value": 4724.19701510988, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.6090297790586, 337.90538649178234, 339.6314998226924, 342.4657534246577, 345.38004282313256, 5301.867453313669, 5311.89376212476, 5338.919916201256, 5357.357117145938, 5376.919346209805, 5045.77294021769, 5185.0, 5201.427842937273, 5214.374275086985, 5273.419923808378, 4065.6544193743566, 4100.40544121103, 4224.8521301754445, 4246.57534246576, 4258.169656916903, 4151.294279972397, 4193.056562273495, 4209.831606735727, 4227.598378154071, 4261.808218630169, 4090.7750073745933, 4164.354525048622, 4165.875023749277, 4170.228507726065, 4209.473815773028, 4150.149219410044, 4156.607536475284, 4161.313383291753, 4194.433751443562, 4201.789910504478, 3733.9439908401123, 3736.159364143073, 3742.0, 3751.5498140223076, 3752.45589389537, 5003.2995380646735, 5185.222216667492, 5191.558717509016, 5203.505666961632, 5213.687178769286, 4756.952430475685, 4793.3049707792525, 4806.158922188638, 4807.591354734852, 4845.539673731025, 5513.365962914236, 5540.722963851791, 5567.3040869891265, 5572.024895643246, 5599.692016939067, 3798.525184351956, 4005.439238506611, 4102.856400025985, 4120.505539335269, 4129.483814523184, 5693.146028095777, 5711.457906365258, 5738.196652468658, 5746.787486817563, 5769.509591684669, 4053.3919912012843, 4055.5944405559208, 4068.7558746475306, 4080.3471444568727, 4083.5303940047274, 3969.186316805106, 4143.8964025898995, 4172.248995180836, 4187.5184353799095, 4190.434291370626, 4688.484266730657, 4705.411823522059, 4727.747147006046, 4774.618030557547, 4812.302216178635, 5548.087254191581, 5566.638168519034, 5569.66582005081, 5571.387147413781, 5620.100783874561, 1319.2348437905991, 1359.660734176835, 1429.6497358147321, 1443.7473442147582, 1445.4651778841896, 956.8086382723483, 983.1593987140892, 983.6409710455775, 988.5946761827616, 990.1435258501344, 5256.710880901587, 5268.631195816256, 5281.788728450896, 5291.629585928948, 5292.708901010405, 5601.663900165921, 5673.858153546194, 5681.289838770153, 5688.573356998241, 5698.037020749187, 5093.541581257705, 5362.651427657268, 5367.3827509836865, 5370.704611246419, 5383.892322153535, 2997.7451916586897, 3019.7131272529073, 3030.4848175810166, 3032.742216911543, 3032.7725420593542, 5728.427157284238, 5878.882422351529, 5881.558883083786, 5899.115132730039, 5914.349421563625, 5821.912671309934, 5828.921649494857, 5829.329627092854, 5861.3845546217035, 5863.853403664943, 5890.617109887844, 5909.940900591025, 5913.822585322448, 5924.911126333109, 5925.200097986803, 5397.757100930389, 5638.887222255614, 5708.885822283614, 5721.771129154788, 5764.221830052889, 5653.547716182778, 5672.9221447924765, 5694.8928896308635, 5700.574856285929, 5704.833817231311, 5740.712964351849, 5895.174675545512, 5918.7928422505, 5924.919042098171, 5928.0, 3878.4570160178164, 3996.2207369562657, 4004.9199016020098, 4007.8797636070963, 4012.4583181270154, 5577.466196795751, 5693.743781529758, 5694.886102277848, 5719.428057194414, 5719.828405147852, 5491.75287112073, 5523.171524271392, 5523.7238138093735, 5551.195076713934, 5589.161625756169, 5307.761150748149, 5538.369274594535, 5572.86067848307, 5574.9721251393485, 5600.159976003632, 5430.83707488776, 5458.344998600193, 5460.34475862899, 5490.725463726878, 5494.2582751329655, 5520.3990842655185, 5547.6408279971665, 5567.638103523258, 5582.218489411404, 5584.4136365681015, 5204.375474943031, 5461.371942226695, 5465.344158700822, 5480.3971563128825, 5482.17767334903], "value": 4254.616450648178, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [750.4034292737276, 762.7559181062059, 793.9603019849006, 853.4452605806222, 863.7711006583253, 16164.272607732652, 16660.58348541287, 16674.0, 16688.666226675457, 16712.91643541782, 14552.272386380691, 15583.688326233498, 15665.0, 15699.921500392482, 15708.607284817885, 662.3343539742552, 792.6551949901782, 809.34038758412, 823.4112609484217, 827.9710210142646, 818.9754307370788, 828.6809578312333, 830.9293710034655, 831.2020460358063, 831.3058596072257, 883.1874675298741, 886.5345693510894, 887.7026196224273, 888.773362792487, 889.0531583863165, 689.2762599270745, 838.9370797190174, 862.5169904853302, 881.0132651430375, 882.668999125328, 625.9155014073087, 626.2516293029831, 626.3423405424286, 626.8746250749823, 626.9529785266124, 15404.46084387041, 15487.225638718131, 15491.845081549265, 15515.844841551552, 15525.0, 14283.0, 14724.33740481681, 14755.26223688822, 14808.481703140338, 14956.102633841896, 12760.0, 12867.48530058806, 12964.87035129646, 13121.0, 13224.603261902159, 4401.207782599131, 4520.276755719069, 4580.862574122782, 4684.625229981593, 4686.968866849288, 76866.0, 76899.23100769032, 77622.61188694077, 77927.0, 78162.60918695427, 11054.060404865515, 11115.388653624128, 11377.715557111138, 11385.0, 11564.537418503334, 21633.0, 21650.783492164875, 21668.45828854291, 21670.13319467235, 21711.674324885138, 7004.894926576106, 8063.798405039921, 8208.425410221227, 8671.176238257356, 8684.131586841266, 4726.125666751662, 4733.5503127202865, 4779.808807647655, 4797.4482934462985, 4910.435299940481, 3537.7169826413824, 3648.562172539312, 3656.963430365662, 3657.341678497896, 3665.7983810890123, 643.5945354426681, 650.7397041183565, 651.6513665189101, 656.2485953583121, 659.4427708586237, 632.2886752403546, 661.4736052286037, 670.6143967218771, 681.3152781454705, 684.0560027162584, 9139.725808225763, 9410.576524056298, 9492.335536512377, 9609.855852162224, 9925.156361709336, 583.0700033446645, 631.9684015799191, 647.7117682631279, 686.6017709728445, 686.6360828760764, 4535.297028960516, 4536.0, 4551.795169217393, 4811.133995880705, 5183.81856635016, 120062.0, 120354.57517973833, 122578.0, 126152.0, 128617.0, 107158.0, 110737.0, 111941.0, 113139.0, 114577.0, 98166.0, 98576.0, 100715.49642251742, 100855.0, 102882.0, 10367.740806479898, 10534.209934254808, 10728.570857165625, 10787.406692631981, 10789.244752867224, 56007.0, 56376.43623563712, 56587.43412565822, 56917.43082569287, 58366.83266334735, 81705.59147204226, 83815.580922095, 83856.58071709602, 86440.5677971606, 87416.562917185, 13608.183508989492, 13739.862601373858, 13826.723465530835, 13900.860991390362, 13996.16023038621, 15467.0, 15615.687686246438, 15704.685906282039, 15772.132532710351, 15958.281877315781, 14508.492202773274, 14674.309223994118, 14768.18774967387, 14986.625334366729, 15518.84481155174, 13761.0, 14355.210463424612, 14947.701045978802, 14989.550313490612, 15580.766288505909, 14468.85531144675, 14489.347979340744, 14740.705185896437, 14788.11271323724, 15401.229938503255, 11509.550826251965, 11893.76212475763, 11912.880871191177, 12584.18202816814, 12922.806157907828, 11259.887401125883, 11554.48004839801, 11642.5342986283, 12043.156979011384, 12175.817362739388], "value": 7896.3640049274445, "unit": "score"}], "name": "dromaeo_css", "value": 7421.510558632109, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 110.0, 111.0, 111.0, 110.0, 111.0, 110.0, 110.0, 111.0, 109.0], "value": 109.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 116.0, 118.0, 187.0, 116.0, 121.0, 148.0, 119.0, 148.0, 117.0], "value": 133.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 159.0, 159.0, 160.0, 162.0, 162.0, 160.0, 160.0, 160.0], "value": 160.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 76.0, 110.0, 75.0, 77.0, 74.0, 75.0, 100.0, 74.0], "value": 80.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 120.0, 82.0, 82.0, 84.0, 128.0, 82.0, 83.0, 132.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0, 146.0, 146.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 49.0, 51.0, 51.0, 51.0, 51.0, 53.0, 50.0, 49.0, 50.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0, 62.0, 63.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 83.0, 79.0, 75.0, 78.0, 79.0, 84.0, 81.0, 75.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 127.0, 129.0, 132.0, 119.0, 126.0, 122.0, 126.0, 155.0, 126.0], "value": 128.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 155.0, 160.0, 157.0, 155.0, 156.0, 155.0, 158.0, 157.0, 157.0], "value": 156.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 65.0, 61.0, 62.0, 62.0, 62.0, 66.0], "value": 62.9, "unit": "score"}], "name": "kraken", "value": 1475.7, "alertThreshold": 2.0}]} 04:52:27 INFO - Return code: 0 04:52:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:52:27 INFO - # TBPL SUCCESS # 04:52:27 INFO - Running post-action listener: _resource_record_post_action 04:52:27 INFO - ##### 04:52:27 INFO - ##### Finished run-tests step (success) 04:52:27 INFO - ##### 04:52:27 INFO - Running post-run listener: _resource_record_post_run 04:52:27 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 555679744; Read time: 12; Write time: 583052 04:52:27 INFO - TinderboxPrint: CPU usage
13.0% 04:52:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 04:52:27 INFO - TinderboxPrint: I/O write bytes / time
555,679,744 / 583,052 04:52:27 INFO - TinderboxPrint: CPU idle
8,276.3 (87.0%) 04:52:27 INFO - TinderboxPrint: CPU user
1,193.4 (12.5%) 04:52:27 INFO - TinderboxPrint: Swap in / out
0 / 0 04:52:27 INFO - install - Wall time: 18s; CPU: 14.0%; Read bytes: 0; Write bytes: 256831488; Read time: 0; Write time: 339212 04:52:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 298229760; Read time: 0; Write time: 243840 04:52:27 INFO - Running post-run listener: _upload_blobber_files 04:52:27 INFO - Blob upload gear active. 04:52:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:52:27 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 04:52:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:52:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:52:28 INFO - (blobuploader) - INFO - Open directory for files ... 04:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:52:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:31 INFO - (blobuploader) - INFO - Done attempting. 04:52:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:52:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:52:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:32 INFO - (blobuploader) - INFO - Done attempting. 04:52:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:52:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:52:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:52:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:52:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:52:33 INFO - (blobuploader) - INFO - Done attempting. 04:52:33 INFO - (blobuploader) - INFO - Iteration through files over. 04:52:33 INFO - Return code: 0 04:52:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:52:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:52:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03ecac1076a7048d84bf6ad1c0a3193bf80f072cea7a2b2dc0d90e7746accd60713202953e899f453f390c242645322e8b46b78bec99a79f1d277cfdb396cfe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8ce5b6fd728fa2f9a109bd58d24744205d8507ebc7816bc4d19e7cac68ac9f9c88db6c1ff5cdea4ce9bb2c26cff32191c49cb1b033abee49853380a63767a2da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a8e7555de5029d38e8936a8b3f1b9cdf860801a3bdf4db881b91573731f0cadeec7be1e4dca4d81c808268357d39651e9a279bb0bb91b9ae4a3b44c3922c73bd"} 04:52:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:52:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:52:33 INFO - Contents: 04:52:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03ecac1076a7048d84bf6ad1c0a3193bf80f072cea7a2b2dc0d90e7746accd60713202953e899f453f390c242645322e8b46b78bec99a79f1d277cfdb396cfe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8ce5b6fd728fa2f9a109bd58d24744205d8507ebc7816bc4d19e7cac68ac9f9c88db6c1ff5cdea4ce9bb2c26cff32191c49cb1b033abee49853380a63767a2da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a8e7555de5029d38e8936a8b3f1b9cdf860801a3bdf4db881b91573731f0cadeec7be1e4dca4d81c808268357d39651e9a279bb0bb91b9ae4a3b44c3922c73bd"} 04:52:33 INFO - Running post-run listener: copy_logs_to_upload_dir 04:52:33 INFO - Copying logs to upload dir... 04:52:33 INFO - mkdir: /builds/slave/test/build/upload/logs 04:52:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.401785 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 34 secs) (at 2016-07-16 04:52:33.188329) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-16 04:52:33.191815) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/03ecac1076a7048d84bf6ad1c0a3193bf80f072cea7a2b2dc0d90e7746accd60713202953e899f453f390c242645322e8b46b78bec99a79f1d277cfdb396cfe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8ce5b6fd728fa2f9a109bd58d24744205d8507ebc7816bc4d19e7cac68ac9f9c88db6c1ff5cdea4ce9bb2c26cff32191c49cb1b033abee49853380a63767a2da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a8e7555de5029d38e8936a8b3f1b9cdf860801a3bdf4db881b91573731f0cadeec7be1e4dca4d81c808268357d39651e9a279bb0bb91b9ae4a3b44c3922c73bd"} build_url:https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/S8S5EdfJS9CVdie44df4Lw/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/Mozilla-Inbound/sha512/03ecac1076a7048d84bf6ad1c0a3193bf80f072cea7a2b2dc0d90e7746accd60713202953e899f453f390c242645322e8b46b78bec99a79f1d277cfdb396cfe9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/8ce5b6fd728fa2f9a109bd58d24744205d8507ebc7816bc4d19e7cac68ac9f9c88db6c1ff5cdea4ce9bb2c26cff32191c49cb1b033abee49853380a63767a2da", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/a8e7555de5029d38e8936a8b3f1b9cdf860801a3bdf4db881b91573731f0cadeec7be1e4dca4d81c808268357d39651e9a279bb0bb91b9ae4a3b44c3922c73bd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-16 04:52:33.218471) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:52:33.218876) ========= 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=5bd038846534b2fdfa68eeae000001e2-1468668716.700495-1185484444 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004470 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 04:52:33.303710) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 04:52:33.304049) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 04:52:33.304405) ========= ========= Total master_lag: 0.16 =========