builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-065 starttime: 1468632047.89 results: success (0) buildid: 20160715165053 builduid: 92d00abb945d4521b30d20be31ef0929 revision: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:47.886813) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:47.887228) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:47.887507) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:47.903218) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:47.903474) ========= 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=523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 18:20:48-- 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% 113M=0s 2016-07-15 18:20:48 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097520 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:48.602188) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:48.602543) ========= 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=523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017448 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:48.635898) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 18:20:48.636246) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c --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=523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 18:20:49,488 truncating revision to first 12 chars 2016-07-15 18:20:49,488 Setting DEBUG logging. 2016-07-15 18:20:49,488 attempt 1/10 2016-07-15 18:20:49,488 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0d82d5d030af?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 18:20:50,769 unpacking tar archive at: mozilla-central-0d82d5d030af/testing/mozharness/ program finished with exit code 0 elapsedTime=1.615433 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 18:20:50.268131) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:50.268443) ========= script_repo_revision: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:50.268809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:50.269074) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 18:20:50.278228) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-15 18:20:50.278581) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:20:51 INFO - MultiFileLogger online at 20160715 18:20:51 in /builds/slave/test 18:20:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:20:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:51 INFO - {'append_to_log': False, 18:20:51 INFO - 'base_work_dir': '/builds/slave/test', 18:20:51 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:20:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:51 INFO - 'branch': 'Firefox-Non-PGO', 18:20:51 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:51 INFO - 'config_files': ('talos/linux_config.py',), 18:20:51 INFO - 'default_actions': ('clobber', 18:20:51 INFO - 'read-buildbot-config', 18:20:51 INFO - 'download-and-extract', 18:20:51 INFO - 'populate-webroot', 18:20:51 INFO - 'create-virtualenv', 18:20:51 INFO - 'install', 18:20:51 INFO - 'run-tests'), 18:20:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:51 INFO - 'download_minidump_stackwalk': True, 18:20:51 INFO - 'download_symbols': 'ondemand', 18:20:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:20:51 INFO - 'tooltool.py': '/tools/tooltool.py', 18:20:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:20:51 INFO - '/tools/misc-python/virtualenv.py')}, 18:20:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:51 INFO - 'installer_path': 'installer.exe', 18:20:51 INFO - 'log_level': 'info', 18:20:51 INFO - 'log_name': 'talos', 18:20:51 INFO - 'log_to_console': True, 18:20:51 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:20:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:20:51 INFO - 'opt_config_files': (), 18:20:51 INFO - 'pip_index': False, 18:20:51 INFO - 'sps_profile': False, 18:20:51 INFO - 'sps_profile_interval': 0, 18:20:51 INFO - 'suite': 'dromaeojs', 18:20:51 INFO - 'system_bits': '32', 18:20:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:51 INFO - 'title': 'talos-linux64-ix-065', 18:20:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:51 INFO - 'use_talos_json': True, 18:20:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:51 INFO - 'work_dir': 'build'} 18:20:51 INFO - ##### 18:20:51 INFO - ##### Running clobber step. 18:20:51 INFO - ##### 18:20:51 INFO - Running pre-action listener: _resource_record_pre_action 18:20:51 INFO - Running main action method: clobber 18:20:51 INFO - rmtree: /builds/slave/test/build 18:20:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:52 INFO - Running post-action listener: _resource_record_post_action 18:20:52 INFO - ##### 18:20:52 INFO - ##### Finished clobber step (success) 18:20:52 INFO - ##### 18:20:52 INFO - ##### 18:20:52 INFO - ##### Running read-buildbot-config step. 18:20:52 INFO - ##### 18:20:52 INFO - Running pre-action listener: _resource_record_pre_action 18:20:52 INFO - Running main action method: read_buildbot_config 18:20:52 INFO - Using buildbot properties: 18:20:52 INFO - { 18:20:52 INFO - "project": "", 18:20:52 INFO - "product": "firefox", 18:20:52 INFO - "script_repo_revision": "production", 18:20:52 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 18:20:52 INFO - "repository": "", 18:20:52 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 18:20:52 INFO - "buildid": "20160715165053", 18:20:52 INFO - "pgo_build": "False", 18:20:52 INFO - "basedir": "/builds/slave/test", 18:20:52 INFO - "buildnumber": 563, 18:20:52 INFO - "slavename": "talos-linux64-ix-065", 18:20:52 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:20:52 INFO - "platform": "ubuntu64_hw", 18:20:52 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:20:52 INFO - "branch": "mozilla-central", 18:20:52 INFO - "repo_path": "mozilla-central", 18:20:52 INFO - "revision": "0d82d5d030afa2e8f48dd68e86eb75efd0947a5c", 18:20:52 INFO - "stage_platform": "linux64", 18:20:52 INFO - "builduid": "92d00abb945d4521b30d20be31ef0929", 18:20:52 INFO - "slavebuilddir": "test" 18:20:52 INFO - } 18:20:52 INFO - Found installer url https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 18:20:52 INFO - Running post-action listener: _resource_record_post_action 18:20:52 INFO - ##### 18:20:52 INFO - ##### Finished read-buildbot-config step (success) 18:20:52 INFO - ##### 18:20:52 INFO - ##### 18:20:52 INFO - ##### Running download-and-extract step. 18:20:52 INFO - ##### 18:20:52 INFO - Running pre-action listener: _resource_record_pre_action 18:20:52 INFO - Running main action method: download_and_extract 18:20:52 INFO - mkdir: /builds/slave/test/build/tests 18:20:52 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')]} 18:20:52 INFO - https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 18:20:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:20:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:20:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:52 INFO - Downloaded 1361 bytes. 18:20:52 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 18:20:52 INFO - Using the following test package requirements: 18:20:52 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:20:52 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:20:52 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 18:20:52 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'jsshell-linux-x86_64.zip'], 18:20:52 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:20:52 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 18:20:52 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:20:52 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:20:52 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:20:52 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 18:20:52 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:20:52 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:20:52 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')]} 18:20:52 INFO - https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:20:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:20:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:20:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:53 INFO - Downloaded 22757547 bytes. 18:20:53 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'] 18:20:53 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 18:20:55 INFO - Return code: 0 18:20:55 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 18:20:55 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')]} 18:20:55 INFO - https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:20:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:20:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 18:20:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:56 INFO - Downloaded 22757547 bytes. 18:20:56 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'] 18:20:56 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 18:20:57 INFO - Return code: 0 18:20:57 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')]} 18:20:57 INFO - https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:20:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:20:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 18:20:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:58 INFO - Downloaded 14403317 bytes. 18:20:58 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'] 18:20:58 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 18:20:58 INFO - Return code: 0 18:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:20:58 INFO - https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:20:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:20:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:20:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:20:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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 18:21:00 INFO - Downloaded 57059121 bytes. 18:21:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:21:00 INFO - mkdir: /builds/slave/test/properties 18:21:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:21:00 INFO - Writing to file /builds/slave/test/properties/build_url 18:21:00 INFO - Contents: 18:21:00 INFO - build_url:https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 18:21:00 INFO - Running post-action listener: _resource_record_post_action 18:21:01 INFO - Running post-action listener: set_extra_try_arguments 18:21:01 INFO - ##### 18:21:01 INFO - ##### Finished download-and-extract step (success) 18:21:01 INFO - ##### 18:21:01 INFO - ##### 18:21:01 INFO - ##### Running populate-webroot step. 18:21:01 INFO - ##### 18:21:01 INFO - Running pre-action listener: _resource_record_pre_action 18:21:01 INFO - Running main action method: populate_webroot 18:21:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:21:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:21:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:21:01 INFO - '--tppagecycles', 18:21:01 INFO - '7'], 18:21:01 INFO - 'tests': ['tsvgm']}}, 18:21:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:21:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:21:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:21:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:21:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:21:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['damp', 'tps']}, 18:21:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'tests': ['damp', 'tps']}, 18:21:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['dromaeo_dom']}, 18:21:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:21:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['basic_compositor_video']}, 18:21:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:21:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['a11yr', 18:21:01 INFO - 'ts_paint', 18:21:01 INFO - 'tpaint', 18:21:01 INFO - 'sessionrestore', 18:21:01 INFO - 'sessionrestore_no_auto_restore', 18:21:01 INFO - 'tabpaint']}, 18:21:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:21:01 INFO - 'ts_paint', 18:21:01 INFO - 'tpaint', 18:21:01 INFO - 'sessionrestore', 18:21:01 INFO - 'sessionrestore_no_auto_restore', 18:21:01 INFO - 'tabpaint']}, 18:21:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:21:01 INFO - 'ts_paint', 18:21:01 INFO - 'tpaint', 18:21:01 INFO - 'sessionrestore', 18:21:01 INFO - 'sessionrestore_no_auto_restore', 18:21:01 INFO - 'tabpaint']}, 18:21:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:21:01 INFO - 'ts_paint', 18:21:01 INFO - 'tpaint', 18:21:01 INFO - 'sessionrestore', 18:21:01 INFO - 'sessionrestore_no_auto_restore', 18:21:01 INFO - 'tabpaint']}, 18:21:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['a11yr', 18:21:01 INFO - 'ts_paint', 18:21:01 INFO - 'tpaint', 18:21:01 INFO - 'sessionrestore', 18:21:01 INFO - 'sessionrestore_no_auto_restore', 18:21:01 INFO - 'tabpaint']}, 18:21:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['a11yr', 18:21:01 INFO - 'ts_paint', 18:21:01 INFO - 'tpaint', 18:21:01 INFO - 'sessionrestore', 18:21:01 INFO - 'sessionrestore_no_auto_restore', 18:21:01 INFO - 'tabpaint']}, 18:21:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['tsvgx', 18:21:01 INFO - 'tsvgr_opacity', 18:21:01 INFO - 'tart', 18:21:01 INFO - 'tscrollx', 18:21:01 INFO - 'cart']}, 18:21:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:21:01 INFO - 'tsvgr_opacity', 18:21:01 INFO - 'tart', 18:21:01 INFO - 'tscrollx', 18:21:01 INFO - 'cart']}, 18:21:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'talos_options': ['--disable-e10s'], 18:21:01 INFO - 'tests': ['tp5o']}, 18:21:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'tests': ['tp5o']}, 18:21:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'talos_options': ['--disable-e10s', 18:21:01 INFO - '--xperf_path', 18:21:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:01 INFO - 'tests': ['tp5n']}, 18:21:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:21:01 INFO - 'talos_options': ['--xperf_path', 18:21:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:21:01 INFO - 'tests': ['tp5n']}}, 18:21:01 INFO - 'talos.zip': {'path': '', 18:21:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:21:01 INFO - Running post-action listener: _resource_record_post_action 18:21:01 INFO - ##### 18:21:01 INFO - ##### Finished populate-webroot step (success) 18:21:01 INFO - ##### 18:21:01 INFO - ##### 18:21:01 INFO - ##### Running create-virtualenv step. 18:21:01 INFO - ##### 18:21:01 INFO - Running pre-action listener: _resource_record_pre_action 18:21:01 INFO - Running main action method: create_virtualenv 18:21:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:21:01 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 18:21:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:21:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:21:01 INFO - Using real prefix '/usr' 18:21:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:21:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:21:03 INFO - Installing pip.................done. 18:21:03 INFO - Return code: 0 18:21:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:21: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')]} 18:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21: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')]} 18:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:03 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:03 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 18:21:03 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 18:21:03 INFO - Using env: {'DISPLAY': ':0', 18:21:03 INFO - 'HOME': '/home/cltbld', 18:21:03 INFO - 'LANG': 'en_US.UTF-8', 18:21:03 INFO - 'LANGUAGE': 'en_US:en', 18:21:03 INFO - 'LOGNAME': 'cltbld', 18:21:03 INFO - 'MAIL': '/var/mail/cltbld', 18:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:03 INFO - 'MOZ_NO_REMOTE': '1', 18:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:03 INFO - 'NO_EM_RESTART': '1', 18:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:03 INFO - 'PWD': '/builds/slave/test', 18:21:03 INFO - 'SHELL': '/bin/bash', 18:21:03 INFO - 'SHLVL': '1', 18:21:03 INFO - 'TERM': 'linux', 18:21:03 INFO - 'TMOUT': '86400', 18:21:03 INFO - 'USER': 'cltbld', 18:21:03 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:03 INFO - '_': '/tools/buildbot/bin/python'} 18:21:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:03 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 18:21:03 INFO - Cleaning up... 18:21:03 INFO - Return code: 0 18:21:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:21: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')]} 18:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21: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')]} 18:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:03 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:03 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 18:21:03 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 18:21:03 INFO - Using env: {'DISPLAY': ':0', 18:21:03 INFO - 'HOME': '/home/cltbld', 18:21:03 INFO - 'LANG': 'en_US.UTF-8', 18:21:03 INFO - 'LANGUAGE': 'en_US:en', 18:21:03 INFO - 'LOGNAME': 'cltbld', 18:21:03 INFO - 'MAIL': '/var/mail/cltbld', 18:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:03 INFO - 'MOZ_NO_REMOTE': '1', 18:21:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:03 INFO - 'NO_EM_RESTART': '1', 18:21:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:03 INFO - 'PWD': '/builds/slave/test', 18:21:03 INFO - 'SHELL': '/bin/bash', 18:21:03 INFO - 'SHLVL': '1', 18:21:03 INFO - 'TERM': 'linux', 18:21:03 INFO - 'TMOUT': '86400', 18:21:03 INFO - 'USER': 'cltbld', 18:21:03 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:03 INFO - '_': '/tools/buildbot/bin/python'} 18:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:04 INFO - Downloading/unpacking psutil>=3.1.1 18:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:21:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:21:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:21:06 INFO - Installing collected packages: psutil 18:21:06 INFO - Running setup.py install for psutil 18:21:06 INFO - building 'psutil._psutil_linux' extension 18:21:06 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 18:21:06 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 18:21:06 INFO - building 'psutil._psutil_posix' extension 18:21:06 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 18:21:06 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 18:21:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:21:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:21:06 INFO - Successfully installed psutil 18:21:06 INFO - Cleaning up... 18:21:06 INFO - Return code: 0 18:21:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:21:06 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')]} 18:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:06 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')]} 18:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:06 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:06 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 18:21:06 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 18:21:06 INFO - Using env: {'DISPLAY': ':0', 18:21:06 INFO - 'HOME': '/home/cltbld', 18:21:06 INFO - 'LANG': 'en_US.UTF-8', 18:21:06 INFO - 'LANGUAGE': 'en_US:en', 18:21:06 INFO - 'LOGNAME': 'cltbld', 18:21:06 INFO - 'MAIL': '/var/mail/cltbld', 18:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:06 INFO - 'MOZ_NO_REMOTE': '1', 18:21:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:06 INFO - 'NO_EM_RESTART': '1', 18:21:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:06 INFO - 'PWD': '/builds/slave/test', 18:21:06 INFO - 'SHELL': '/bin/bash', 18:21:06 INFO - 'SHLVL': '1', 18:21:06 INFO - 'TERM': 'linux', 18:21:06 INFO - 'TMOUT': '86400', 18:21:06 INFO - 'USER': 'cltbld', 18:21:06 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:06 INFO - '_': '/tools/buildbot/bin/python'} 18:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:21:06 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 18:21:06 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 18:21:06 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 18:21:06 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 18:21:06 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 18:21:06 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 18:21:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:21:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:21:08 INFO - Installing collected packages: mozsystemmonitor 18:21:08 INFO - Running setup.py install for mozsystemmonitor 18:21:08 INFO - Successfully installed mozsystemmonitor 18:21:08 INFO - Cleaning up... 18:21:09 INFO - Return code: 0 18:21:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:09 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:09 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 18:21:09 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 18:21:09 INFO - Using env: {'DISPLAY': ':0', 18:21:09 INFO - 'HOME': '/home/cltbld', 18:21:09 INFO - 'LANG': 'en_US.UTF-8', 18:21:09 INFO - 'LANGUAGE': 'en_US:en', 18:21:09 INFO - 'LOGNAME': 'cltbld', 18:21:09 INFO - 'MAIL': '/var/mail/cltbld', 18:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:09 INFO - 'MOZ_NO_REMOTE': '1', 18:21:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:09 INFO - 'NO_EM_RESTART': '1', 18:21:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:09 INFO - 'PWD': '/builds/slave/test', 18:21:09 INFO - 'SHELL': '/bin/bash', 18:21:09 INFO - 'SHLVL': '1', 18:21:09 INFO - 'TERM': 'linux', 18:21:09 INFO - 'TMOUT': '86400', 18:21:09 INFO - 'USER': 'cltbld', 18:21:09 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:09 INFO - '_': '/tools/buildbot/bin/python'} 18:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:09 INFO - Downloading/unpacking jsonschema==2.5.1 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:11 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:21:11 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 INFO - Downloading functools32-3.2.3-2.tar.gz 18:21:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:21:11 INFO - warning: no files found matching '*.txt' 18:21:11 INFO - no previously-included directories found matching 'build' 18:21:11 INFO - no previously-included directories found matching 'dist' 18:21:11 INFO - no previously-included directories found matching '.git*' 18:21:11 INFO - Installing collected packages: jsonschema, functools32 18:21:11 INFO - Running setup.py install for functools32 18:21:11 INFO - warning: no files found matching '*.txt' 18:21:11 INFO - no previously-included directories found matching 'build' 18:21:11 INFO - no previously-included directories found matching 'dist' 18:21:11 INFO - no previously-included directories found matching '.git*' 18:21:11 INFO - Successfully installed jsonschema functools32 18:21:11 INFO - Cleaning up... 18:21:11 INFO - Return code: 0 18:21:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:11 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:11 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 18:21:11 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 18:21:11 INFO - Using env: {'DISPLAY': ':0', 18:21:11 INFO - 'HOME': '/home/cltbld', 18:21:11 INFO - 'LANG': 'en_US.UTF-8', 18:21:11 INFO - 'LANGUAGE': 'en_US:en', 18:21:11 INFO - 'LOGNAME': 'cltbld', 18:21:11 INFO - 'MAIL': '/var/mail/cltbld', 18:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:11 INFO - 'MOZ_NO_REMOTE': '1', 18:21:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:11 INFO - 'NO_EM_RESTART': '1', 18:21:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:11 INFO - 'PWD': '/builds/slave/test', 18:21:11 INFO - 'SHELL': '/bin/bash', 18:21:11 INFO - 'SHLVL': '1', 18:21:11 INFO - 'TERM': 'linux', 18:21:11 INFO - 'TMOUT': '86400', 18:21:11 INFO - 'USER': 'cltbld', 18:21:11 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:11 INFO - '_': '/tools/buildbot/bin/python'} 18:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:11 INFO - Downloading/unpacking blobuploader==1.2.4 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 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 18:21:11 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 18:21:13 INFO - Downloading blobuploader-1.2.4.tar.gz 18:21:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:21:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:21:13 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 18:21:13 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 18:21:13 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 18:21:13 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 18:21:13 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 18:21:13 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 18:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:21:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:21:14 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 18:21:14 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 18:21:14 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 18:21:14 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 18:21:14 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 18:21:14 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 18:21:14 INFO - Downloading docopt-0.6.1.tar.gz 18:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:21:14 INFO - Installing collected packages: blobuploader, requests, docopt 18:21:14 INFO - Running setup.py install for blobuploader 18:21:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:21:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:21:14 INFO - Running setup.py install for requests 18:21:14 INFO - Running setup.py install for docopt 18:21:15 INFO - Successfully installed blobuploader requests docopt 18:21:15 INFO - Cleaning up... 18:21:15 INFO - Return code: 0 18:21:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21: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')]} 18:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21: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')]} 18:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:15 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:15 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 18:21:15 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 18:21:15 INFO - Using env: {'DISPLAY': ':0', 18:21:15 INFO - 'HOME': '/home/cltbld', 18:21:15 INFO - 'LANG': 'en_US.UTF-8', 18:21:15 INFO - 'LANGUAGE': 'en_US:en', 18:21:15 INFO - 'LOGNAME': 'cltbld', 18:21:15 INFO - 'MAIL': '/var/mail/cltbld', 18:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:15 INFO - 'MOZ_NO_REMOTE': '1', 18:21:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:15 INFO - 'NO_EM_RESTART': '1', 18:21:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:15 INFO - 'PWD': '/builds/slave/test', 18:21:15 INFO - 'SHELL': '/bin/bash', 18:21:15 INFO - 'SHLVL': '1', 18:21:15 INFO - 'TERM': 'linux', 18:21:15 INFO - 'TMOUT': '86400', 18:21:15 INFO - 'USER': 'cltbld', 18:21:15 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:15 INFO - '_': '/tools/buildbot/bin/python'} 18:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:15 INFO - Running setup.py (path:/tmp/pip-fZjPan-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:15 INFO - Running setup.py (path:/tmp/pip-QuG2We-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:15 INFO - Running setup.py (path:/tmp/pip-M_lk1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:15 INFO - Running setup.py (path:/tmp/pip-4sUMNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:15 INFO - Running setup.py (path:/tmp/pip-_lEyue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:15 INFO - Running setup.py (path:/tmp/pip-oMenbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:15 INFO - Running setup.py (path:/tmp/pip-1nD3tu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:15 INFO - Running setup.py (path:/tmp/pip-P7k2wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:15 INFO - Running setup.py (path:/tmp/pip-d9IxQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:15 INFO - Running setup.py (path:/tmp/pip-I5EJT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:16 INFO - Running setup.py (path:/tmp/pip-og7xla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:16 INFO - Running setup.py (path:/tmp/pip-oawn4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:16 INFO - Running setup.py (path:/tmp/pip-4WG3ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:16 INFO - Running setup.py (path:/tmp/pip-wKN3za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:16 INFO - Running setup.py (path:/tmp/pip-EQebag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:16 INFO - Running setup.py (path:/tmp/pip-FJNFgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:16 INFO - Running setup.py (path:/tmp/pip-9cWDSa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:21:16 INFO - Running setup.py install for manifestparser 18:21:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:21:16 INFO - Running setup.py install for mozcrash 18:21:16 INFO - Running setup.py install for mozdebug 18:21:16 INFO - Running setup.py install for mozdevice 18:21:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:21:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:21:16 INFO - Running setup.py install for mozfile 18:21:17 INFO - Running setup.py install for mozhttpd 18:21:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Running setup.py install for mozinfo 18:21:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Running setup.py install for mozInstall 18:21:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Running setup.py install for mozleak 18:21:17 INFO - Running setup.py install for mozlog 18:21:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Running setup.py install for moznetwork 18:21:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Running setup.py install for mozprocess 18:21:17 INFO - Running setup.py install for mozprofile 18:21:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:21:17 INFO - Running setup.py install for mozrunner 18:21:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:21:18 INFO - Running setup.py install for mozscreenshot 18:21:18 INFO - Running setup.py install for moztest 18:21:18 INFO - Running setup.py install for mozversion 18:21:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:21:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:21:18 INFO - Cleaning up... 18:21:18 INFO - Return code: 0 18:21:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21: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')]} 18:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21: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')]} 18:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:18 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:18 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 18:21:18 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 18:21:18 INFO - Using env: {'DISPLAY': ':0', 18:21:18 INFO - 'HOME': '/home/cltbld', 18:21:18 INFO - 'LANG': 'en_US.UTF-8', 18:21:18 INFO - 'LANGUAGE': 'en_US:en', 18:21:18 INFO - 'LOGNAME': 'cltbld', 18:21:18 INFO - 'MAIL': '/var/mail/cltbld', 18:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:18 INFO - 'MOZ_NO_REMOTE': '1', 18:21:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:18 INFO - 'NO_EM_RESTART': '1', 18:21:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:18 INFO - 'PWD': '/builds/slave/test', 18:21:18 INFO - 'SHELL': '/bin/bash', 18:21:18 INFO - 'SHLVL': '1', 18:21:18 INFO - 'TERM': 'linux', 18:21:18 INFO - 'TMOUT': '86400', 18:21:18 INFO - 'USER': 'cltbld', 18:21:18 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:18 INFO - '_': '/tools/buildbot/bin/python'} 18:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:21:18 INFO - Running setup.py (path:/tmp/pip-1tDfAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:21:18 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)) 18:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:21:18 INFO - Running setup.py (path:/tmp/pip-9JxUOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:21:18 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)) 18:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:21:18 INFO - Running setup.py (path:/tmp/pip-znR8Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:21:18 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)) 18:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:21:18 INFO - Running setup.py (path:/tmp/pip-ovQ6N3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:21:18 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)) 18:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:21:18 INFO - Running setup.py (path:/tmp/pip-vp7K3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:21:18 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)) 18:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:21:18 INFO - Running setup.py (path:/tmp/pip-NJ5eEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:21:19 INFO - Running setup.py (path:/tmp/pip-DI7jj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:21:19 INFO - Running setup.py (path:/tmp/pip-210t5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:21:19 INFO - Running setup.py (path:/tmp/pip-cfhONW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:21:19 INFO - Running setup.py (path:/tmp/pip-ncn6iQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:21:19 INFO - Running setup.py (path:/tmp/pip-iHBSHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:21:19 INFO - Running setup.py (path:/tmp/pip-0IwSOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:21:19 INFO - Running setup.py (path:/tmp/pip-nUogH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:21:19 INFO - Running setup.py (path:/tmp/pip-2H4ALl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:19 INFO - Running setup.py (path:/tmp/pip-3eVm3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:21:19 INFO - Running setup.py (path:/tmp/pip-QxnCni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:21:19 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)) 18:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:21:19 INFO - Running setup.py (path:/tmp/pip-PuROtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:21:19 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)) 18:21:19 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)) 18:21:19 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)) 18:21:19 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)) 18:21:19 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)) 18:21:19 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)) 18:21:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:21:22 INFO - Downloading blessings-1.6.tar.gz 18:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:21:22 INFO - Installing collected packages: blessings 18:21:22 INFO - Running setup.py install for blessings 18:21:22 INFO - Successfully installed blessings 18:21:22 INFO - Cleaning up... 18:21:22 INFO - Return code: 0 18:21:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:21:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:22 INFO - Reading from file tmpfile_stdout 18:21:22 INFO - Current package versions: 18:21:22 INFO - argparse == 1.2.1 18:21:22 INFO - blessings == 1.6 18:21:22 INFO - blobuploader == 1.2.4 18:21:22 INFO - docopt == 0.6.1 18:21:22 INFO - functools32 == 3.2.3-2 18:21:22 INFO - jsonschema == 2.5.1 18:21:22 INFO - manifestparser == 1.1 18:21:22 INFO - mozInstall == 1.12 18:21:22 INFO - mozcrash == 0.17 18:21:22 INFO - mozdebug == 0.1 18:21:22 INFO - mozdevice == 0.48 18:21:22 INFO - mozfile == 1.2 18:21:22 INFO - mozhttpd == 0.7 18:21:22 INFO - mozinfo == 0.9 18:21:22 INFO - mozleak == 0.1 18:21:22 INFO - mozlog == 3.2 18:21:22 INFO - moznetwork == 0.27 18:21:22 INFO - mozprocess == 0.23 18:21:22 INFO - mozprofile == 0.28 18:21:22 INFO - mozrunner == 6.12 18:21:22 INFO - mozscreenshot == 0.1 18:21:22 INFO - mozsystemmonitor == 0.3 18:21:22 INFO - moztest == 0.7 18:21:22 INFO - mozversion == 1.4 18:21:22 INFO - psutil == 3.1.1 18:21:22 INFO - requests == 1.2.3 18:21:22 INFO - wsgiref == 0.1.2 18:21:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:22 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 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:22 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 18:21:22 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 18:21:22 INFO - Using env: {'DISPLAY': ':0', 18:21:22 INFO - 'HOME': '/home/cltbld', 18:21:22 INFO - 'LANG': 'en_US.UTF-8', 18:21:22 INFO - 'LANGUAGE': 'en_US:en', 18:21:22 INFO - 'LOGNAME': 'cltbld', 18:21:22 INFO - 'MAIL': '/var/mail/cltbld', 18:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:22 INFO - 'MOZ_NO_REMOTE': '1', 18:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:22 INFO - 'NO_EM_RESTART': '1', 18:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:22 INFO - 'PWD': '/builds/slave/test', 18:21:22 INFO - 'SHELL': '/bin/bash', 18:21:22 INFO - 'SHLVL': '1', 18:21:22 INFO - 'TERM': 'linux', 18:21:22 INFO - 'TMOUT': '86400', 18:21:22 INFO - 'USER': 'cltbld', 18:21:22 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:22 INFO - '_': '/tools/buildbot/bin/python'} 18:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 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)) 18:21:22 INFO - Cleaning up... 18:21:22 INFO - Return code: 0 18:21:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:21:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 18:21:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:21:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:21:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x29bb030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b1e8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2c7c180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, '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': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', '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 18:21:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:21:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:21:22 INFO - Using env: {'DISPLAY': ':0', 18:21:22 INFO - 'HOME': '/home/cltbld', 18:21:22 INFO - 'LANG': 'en_US.UTF-8', 18:21:22 INFO - 'LANGUAGE': 'en_US:en', 18:21:22 INFO - 'LOGNAME': 'cltbld', 18:21:22 INFO - 'MAIL': '/var/mail/cltbld', 18:21:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:22 INFO - 'MOZ_NO_REMOTE': '1', 18:21:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:22 INFO - 'NO_EM_RESTART': '1', 18:21:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:22 INFO - 'PWD': '/builds/slave/test', 18:21:22 INFO - 'SHELL': '/bin/bash', 18:21:22 INFO - 'SHLVL': '1', 18:21:22 INFO - 'TERM': 'linux', 18:21:22 INFO - 'TMOUT': '86400', 18:21:22 INFO - 'USER': 'cltbld', 18:21:22 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:22 INFO - '_': '/tools/buildbot/bin/python'} 18:21:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:21:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:21:22 INFO - Cleaning up... 18:21:22 INFO - Return code: 0 18:21:22 INFO - Running post-action listener: _resource_record_post_action 18:21:22 INFO - Running post-action listener: _start_resource_monitoring 18:21:23 INFO - Starting resource monitoring. 18:21:23 INFO - ##### 18:21:23 INFO - ##### Finished create-virtualenv step (success) 18:21:23 INFO - ##### 18:21:23 INFO - ##### 18:21:23 INFO - ##### Running install step. 18:21:23 INFO - ##### 18:21:23 INFO - Running pre-action listener: _resource_record_pre_action 18:21:23 INFO - Running main action method: install 18:21:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:21:23 INFO - Reading from file tmpfile_stdout 18:21:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:21:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:21:23 INFO - Reading from file tmpfile_stdout 18:21:23 INFO - Output received: 18:21:23 INFO - Usage: mozinstall [options] installer 18:21:23 INFO - Options: 18:21:23 INFO - -h, --help show this help message and exit 18:21:23 INFO - -d DEST, --destination=DEST 18:21:23 INFO - Directory to install application into. [default: 18:21:23 INFO - "/builds/slave/test"] 18:21:23 INFO - --app=APP Application being installed. [default: firefox] 18:21:23 INFO - mkdir: /builds/slave/test/build/application 18:21:23 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'] 18:21:23 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 18:21:39 INFO - Reading from file tmpfile_stdout 18:21:39 INFO - Output received: 18:21:39 INFO - /builds/slave/test/build/application/firefox/firefox 18:21:39 INFO - Running post-action listener: _resource_record_post_action 18:21:39 INFO - ##### 18:21:39 INFO - ##### Finished install step (success) 18:21:39 INFO - ##### 18:21:39 INFO - ##### 18:21:39 INFO - ##### Running run-tests step. 18:21:39 INFO - ##### 18:21:39 INFO - Running pre-action listener: _resource_record_pre_action 18:21:39 INFO - Running main action method: run_tests 18:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:39 INFO - Python 2.7.3 18:21:39 INFO - Return code: 0 18:21:39 INFO - grabbing minidump binary from tooltool 18:21:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:21:39 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 0x2c7a3b0>, '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 0x2c7b2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2c7b740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:39 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 18:21:39 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 18:21:39 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 18:21:39 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:39 INFO - Return code: 0 18:21:39 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:21:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:21:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 18:21:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-065 --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 18:21:39 INFO - Using env: {'DISPLAY': ':0', 18:21:39 INFO - 'HOME': '/home/cltbld', 18:21:39 INFO - 'LANG': 'en_US.UTF-8', 18:21:39 INFO - 'LANGUAGE': 'en_US:en', 18:21:39 INFO - 'LOGNAME': 'cltbld', 18:21:39 INFO - 'MAIL': '/var/mail/cltbld', 18:21:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:21:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:39 INFO - 'MOZ_NO_REMOTE': '1', 18:21:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:21:39 INFO - 'NO_EM_RESTART': '1', 18:21:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:21:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:39 INFO - 'PWD': '/builds/slave/test', 18:21:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:39 INFO - 'SHELL': '/bin/bash', 18:21:39 INFO - 'SHLVL': '1', 18:21:39 INFO - 'TERM': 'linux', 18:21:39 INFO - 'TMOUT': '86400', 18:21:39 INFO - 'USER': 'cltbld', 18:21:39 INFO - 'XDG_SESSION_COOKIE': '523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653', 18:21:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:39 INFO - '_': '/tools/buildbot/bin/python'} 18:21:39 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-065', '--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 18:21:39 INFO - mozversion application_buildid: 20160715165053 18:21:39 INFO - mozversion application_changeset: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c 18:21:39 INFO - mozversion application_display_name: Nightly 18:21:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:39 INFO - mozversion application_name: Firefox 18:21:39 INFO - mozversion application_remotingname: firefox 18:21:39 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:21:39 INFO - mozversion application_vendor: Mozilla 18:21:39 INFO - mozversion application_version: 50.0a1 18:21:39 INFO - mozversion platform_buildid: 20160715165053 18:21:39 INFO - mozversion platform_changeset: 0d82d5d030afa2e8f48dd68e86eb75efd0947a5c 18:21:39 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:21:39 INFO - mozversion platform_version: 50.0a1 18:21:39 INFO - using testdate: 1468632099 18:21:39 INFO - actual date: 1468632099 18:21:39 INFO - starting webserver on 'localhost:46402' 18:21:39 INFO - SUITE-START | Running 2 tests 18:21:39 INFO - TEST-START | dromaeo_css 18:21:39 INFO - operating with platform_type : linux_ 18:21:39 INFO - Initialising browser for dromaeo_css test... 18:21:39 INFO - TEST-INFO | started process 1324 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj9LzJP/profile http://localhost:46402/getInfo.html) 18:21:44 INFO - PROCESS | 1324 | __metrics Screen width/height:1600/1200 18:21:44 INFO - PROCESS | 1324 | colorDepth:24 18:21:44 INFO - PROCESS | 1324 | Browser inner width/height: 1024/697 18:21:44 INFO - PROCESS | 1324 | __metrics 18:21:44 INFO - PROCESS | 1324 | 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" 18:21:44 INFO - PROCESS | 1324 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:21:45 INFO - TEST-INFO | 1324: exit 0 18:21:45 INFO - Browser initialized. 18:21:45 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:45 INFO - TEST-INFO | started process 1405 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj9LzJP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:57 INFO - PROCESS | 1405 | RSS: Main: 159039488 18:21:57 INFO - PROCESS | 1405 | 18:22:49 INFO - PROCESS | 1405 | 1468632169301 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] 18:25:00 INFO - PROCESS | 1405 | Cycle 1(1): loaded http://localhost:46402/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46402/tests/dromaeo/cssquery-ext.html) 18:25:01 INFO - PROCESS | 1405 | RSS: Main: 259940352 18:25:01 INFO - PROCESS | 1405 | 18:28:04 INFO - PROCESS | 1405 | Cycle 1(1): loaded http://localhost:46402/tests/dromaeo/cssquery-ext.html (next: http://localhost:46402/tests/dromaeo/cssquery-jquery.html) 18:28:04 INFO - PROCESS | 1405 | RSS: Main: 238911488 18:28:04 INFO - PROCESS | 1405 | 18:31:07 INFO - PROCESS | 1405 | Cycle 1(1): loaded http://localhost:46402/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46402/tests/dromaeo/cssquery-mootools.html) 18:31:07 INFO - PROCESS | 1405 | RSS: Main: 239849472 18:31:07 INFO - PROCESS | 1405 | 18:34:09 INFO - PROCESS | 1405 | Cycle 1(1): loaded http://localhost:46402/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46402/tests/dromaeo/cssquery-prototype.html) 18:34:10 INFO - PROCESS | 1405 | RSS: Main: 240390144 18:34:10 INFO - PROCESS | 1405 | 18:37:13 INFO - PROCESS | 1405 | Cycle 1(1): loaded http://localhost:46402/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46402/tests/dromaeo/cssquery-yui.html) 18:37:14 INFO - PROCESS | 1405 | RSS: Main: 211664896 18:37:14 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | Cycle 1(1): loaded http://localhost:46402/tests/dromaeo/cssquery-yui.html (next: http://localhost:46402/tests/dromaeo/cssquery-dojo.html) 18:40:17 INFO - PROCESS | 1405 | RSS: Main: 276652032 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | __start_tp_report 18:40:17 INFO - PROCESS | 1405 | _x_x_mozilla_page_load 18:40:17 INFO - PROCESS | 1405 | _x_x_mozilla_page_load_details 18:40:17 INFO - PROCESS | 1405 | |i|pagename|runs| 18:40:17 INFO - PROCESS | 1405 | |0;dojo.html;2618.5941179117235;2620.2663254288805;2657.0301839828467;2660.960085598716;2679.7588217060475;4563.7489938053395;4634.304854271861;4804.399450068741;4847.733374664392;4907.362042934418;4711.693739906904;4716.268978308359;4729.503402142777;4739.73931433771;4798.712077275366;4348.043430445313;4367.563243675623;4422.336649502568;4427.8228870845205;4441.5336389679105;57.37853691422246;4425.77871106445;4459.888502787424;4465.218586747323;4490.640748740109;4387.473503179607;4445.777711114431;4452.376667266585;4480.215962206634;4493.842715504941;4325.221460137174;4328.61042506176;4331.848385306531;4369.978150109229;4372.1255748850035;4312.482502099737;4316.892077698051;4320.956790432118;4330.415393921811;4375.0812329410865;4736.313234580966;4747.145513807513;4827.08285425768;4832.855014349575;4834.709917404932;5113.2330150477355;5121.359830021247;5158.92261616076;5190.429052804227;5206.531412172884;5222.608304377187;5229.398619158807;5234.241035049897;5237.685738855643;5240.187770895517;4643.024964757439;4787.473377928425;4808.61531077513;4821.710697358169;4864.1974074277705;5289.153735402318;5347.465253474621;5350.304460420199;5355.955588660174;5356.571474282048;5083.466236045162;5097.668651537638;5116.079105760924;5137.255098010767;5137.434882162959;4943.406791185081;5021.171506701354;5059.8988020239385;5065.062963351792;5168.560672342893;4967.850964471072;4969.826056088092;4973.825916092919;4999.850004499871;5011.123053465629;4894.94758626897;4941.579965702884;4945.455999840015;4957.182064959241;4997.625178111656;4250.447441832542;4294.957050429456;4298.78506074695;4300.827966881352;4342.044750154961;3795.5635101963076;3813.56144043433;3832.6550610444638;3844.5386553613184;3885.902852428712;4430.269005614037;4432.534583868647;4436.866893993185;4465.397171381886;4477.462704475419;4984.028114517707;5188.792448302026;5203.14148165556;5203.5576975957465;5258.842234732964;4289.978550107292;4316.568343165658;4319.697621166488;4334.306510958295;4360.345948107746;4566.794494247767;4570.428696412949;4572.4055872736335;4582.908341833145;4605.585497305258;4734.786934587952;4743.241081426955;4746;4750.002499475149;4786.425628924551;5014;5036.143855544493;5052.418971818141;5057.949420505748;5076.82231121909;4759.1671457495495;4764.380630518011;4765.0946320199055;4767.237242041257;4807.927881081857;5011.84964451067;5047.2681461187185;5048.697078175174;5052.577201809927;5099.847004589868;4966.602671786176;4967.627427942991;4972.950270497248;5001.899962000813;5004.42449118356;4891.9755401222765;4892.2661600760175;4892.461829198714;4892.877678058077;4945.579625731853;4855.902881942412;4908.969116485435;4915.95084049155;4920.82777102814;4957.479464656303;4915.778789954389;4923.6799608026795;4956.157453232887;4969.503049695001;5045.041442125985;5062.291279220985;5095;5095.464976177596;5099.082165210298;5143;4798.880027999328;4868.026394720999;4993.600511958962;4997.975010125072;5022.547970682656 18:40:17 INFO - PROCESS | 1405 | |1;ext.html;550.9083539079486;575.907854743241;579.9565032622552;585.1135529672549;595.9016762234231;27372.863135684318;39591.60408395919;40477;40684.59315406845;40688.38967415491;41546;41822.7908860456;41861.162776744524;41890;42003.57996420035;7885.053793544768;8105.756827295161;8115.959420202891;8116.310113640318;8118.431709780318;7971;8044.115147333789;8044.8391032179325;8050.194980501961;8052.235037671441;8057.879131813028;8095.230953059479;8099;8100.432969692124;8100.554469504175;8024.839503209932;8056.073551541531;8060.113387527367;8066.717664881701;8068.919310806876;7950.602469876483;7967.641456134488;8007.239312265326;8012.879806802903;8015.398845086584;39614.801925990185;39858.20283594312;40055.39916901249;40071.7996410019;40151.397729034084;2886.3938572899715;2919.8102123362128;2957.7929544931862;3020.894268700585;3059.143439836849;30038.699613004028;30098.84950575255;30490.695093049228;30608.54087188694;31443.842780785948;3233.8059716416856;3344.2475443025123;3421.606515250753;3465.9133521661906;3521.506989021539;255682;257648;258150;259609;259914;48188.75905620449;49980.250296245584;56086;56627.71686141543;56638.7168064157;57133.14300285499;58964.41035589589;59021.70489147527;65643.34356656468;65645.34354656392;15501.767473487907;15608.921955390377;15644.374225031099;15649.687006259812;15650.608734781721;10731;11010.284331518425;11650;11691.298522088702;11702.239354441934;16022.19889005545;16146.75779863303;17848.46454606364;18268.725969110474;18320.26718931228;6549.214094308713;6700.564463309869;6819.420349270268;7024.789256322217;7057.541259818096;773.3928865840288;871.5598622695625;884.8849649545518;947.6493697332031;977.3256453047517;28772.712272877423;29017.227760391015;29131.271718207216;29273;29730.554041689393;972.1202311907734;993.8906720260766;1005.1857995024054;1044.2690116918188;1094.414488248799;14918;15280.847191528164;15362.769558456632;15372.385104595915;15563;366769;369647;370425;372556;373731;49513;50027.74986125119;50144.74927625339;50252.24621630678;50303;1058.5289546152042;1156.1848896527927;1158.7219067423757;1170.5200867644396;1208.1965492947093;28262.293442664097;29211;29272.707272926997;29604.259893502836;30131.397372052874;38739.80630096832;38866.41700374553;38955.805220973714;38963.415548767356;38988.2202355957;297764;301830;303096;303131;303925;22189.334319970425;22218.333449996528;22560;23332;27087.1873843785;41993.160136797705;42190;42784.14431711411;42826;43145;35126.47310290399;36446;36866.44700329444;36979.260414792094;37105;35994.4600830993;36362.63637363593;36399.81800090983;36852.447213292355;37097.258054839294;35971.28057438784;36945;38312.616873830906;38372.42441363438;41543.79228103961;31647.683523164473;33864;33894;34445.65554344424;34592.65407345894;25399.238022859343;26184.8690756545;26393.472130557664;26422.207333780018;26917.730822691523 18:40:17 INFO - PROCESS | 1405 | |2;jquery.html;345.6146396767605;370.353732736375;372.92355067211236;372.92727918055954;394.37885330604286;12913.354332283396;13504.729905401886;15764.290606922674;17333.82666173338;17368.392106276282;14846.925765371157;15047.84952150481;15051.92474037631;16166.676666466694;16814.411495597655;4896.387951506062;5110.693358398489;5115.053715062707;5118.360204974379;5121.078205922932;3731.1045349116152;5687.203791469197;5977.133315669225;5981.252343457068;6068.757249710006;5170.2503137045105;6005.759769609211;6012.188354572142;6017.398260173992;6043.788467403641;5436.537894278991;5514.558835293207;5553.583481238883;5560.888782224373;5564.8330550083565;5229.660072095302;5329.307190065267;5333.4399888011585;5333.839984800462;5415.864603384947;12232.388380580935;12315.137940344179;12409.131360804748;12507;12517.749645007048;1885.754851869262;1901.1064799544192;1944.8541359398102;1953.7167110769;2056.845736569748;8719.171678690578;8873.245774109208;9055.818883622356;9074.13795689415;9088.77278068047;1657.1631326180323;1682.9326826926908;1724.1551639696459;1755.1265687623238;1817.8475055534261;53262.01927874352;58057.70971145117;69030.96453553201;69452.30547694508;72815.27184728191;9139.77150571241;9477.336586439022;10083.336249518894;10087.49562521871;10149.441780701985;13442.462301508025;13599.59201223965;13608.727825443435;13629.931850340685;13631.386587603582;7389.371903388164;7411.88882166768;7432.665530051161;7457.105147382349;7631.046119235096;7157.785266441911;7212.4951253411755;7292.124945006633;7371.88942165868;7372.889406658905;4201.705880588391;4352.73883566987;4367.366731823868;4378.78106094694;4431.667624928144;1773.893566386021;1812.9456116316533;1815.6005678750653;1844.5665268661853;1853.33280019193;793.5516433215215;823.5429336718059;843.1821133500495;891.4027601507006;903.6475774447971;16244.431444899372;16513.91743041277;16539.173041347884;16656.08391538477;17080.23138958725;868.887044684187;869.0483920107472;870.1950695606539;899.4288626721948;936.866391666078;9143.634254629873;9557.665481708107;9603.663871764455;9616;9811.362261452983;382738;384235;384778;398707;402506;48734;56453;81836;82900;82985;867.0520231213868;887.658251573149;890.2610833008588;902.4089221559889;904.7873749668822;15009.77485337699;15376;15431.61420964485;15617.297221624827;15683;38302;38929.6107038926;39063.609363906;39635.60364396319;39729.80135099306;52310.47689523056;53247;53752;54048.920205068294;54448;7434.59109748969;7634.923650763421;7669.501482403849;7760.922390776246;7928.246816552418;9793.657221997197;11533.480993355151;11806.1145414096;11878.881211187778;12210;9952;10138.23963202748;10333.173346132142;10567.207459440726;10769.246152769545;9515.382384994706;9544.284178686488;10218.642347518098;10400.063994240554;10450.320729152581;10446.477676116316;10596.629117981141;10627.840582391116;10661.307015044307;10867.728306792393;6641.734330626914;6827.48793840454;6857.245702972768;6948.166220053626;7744.728934487266;7924.683012679428;9351.906480935104;10110.49447527606;10535.683929482127;10609.575616975235 18:40:17 INFO - PROCESS | 1405 | |3;mootools.html;1268;1378.9862101378983;1418.0286503744933;1493.6564590144267;1716.3134746101566;6911.965440172805;7411.3700335471485;7668.309852113309;7683.270089341504;7932.484388514757;7591.848163036736;7734.7679569612865;7744.9612751936165;7746.341560967325;7746.457747957646;2170.4573856535867;2202.548477562104;2206.58074965756;2210.171185805323;2214.512807182425;2125.968110478344;2165.069020321261;2184.7050648162535;2187.671849222613;2194.440417693486;2150.7204063471804;2170.70695456113;2176.967345489819;2178.5425060737266;2197.8021978021975;2142.935711928637;2143.217725530186;2150.505383761728;2151.2470635277527;2151.6234658934623;2092.1003968293626;2145.753238377591;2146.6994620753107;2147.5490147069136;2152.1929276521305;7698.499597526143;7707.075150981862;7752.651130699132;7881.133075361706;8185.222403871623;4700.764961751899;4816.133096042711;4823.710577365334;4827.106985207748;4831.661783675144;2807.5227211374117;2861.785366097551;2914.0966300446717;2919.4015226878437;2929.8974535891352;926.684927124774;930.7719608695845;946.5456580841166;950.1686024728363;951.6050838901847;10776.40729759863;10841;10865.728356791064;10872.238943273975;10874.945625271823;3965.385365268388;4048.4939382577177;4186.455760751144;4200.180964711913;4207.600277973587;8622.051574326819;8677.783055423539;8692.478451292942;8694.652213911499;8713.390062695551;4567.2920697291975;4604.309353596987;4611.308303754464;4613.515580864028;4621.0295837873755;3615.7468977171347;3790.7346485746284;3926.541320507237;4286.207051695384;4286.935695964563;3432.9313413731584;3542.1498840278146;3543.94684079739;3584.318979393907;3592.497050412944;1032.7108409645311;1036.7874585710063;1045.7751583409604;1050.2438244463974;1050.3645294596658;792.4334101117613;794.2811755361407;808.3977436809614;809.0170442911773;818.4271010292819;4880.682755620874;4945.505449455026;5066.36670416198;5076.6446348755235;5364.436734142937;376.17992775749127;889.7019498467956;894.8836651235428;899.6086702284528;934.1265916368218;3320.6181289151573;3392.5759280089987;3394.813285269334;3479.043263102667;3588.4617307404096;9693.660721874841;9861.852072218922;9984.251181161442;9994.550245238981;9995.350302230332;9913.752156196153;9931.503424828874;9954.601815927426;9956.900430995742;9986.400815951067;6930.30696930313;7244.45666575009;7257.201707812136;7403.148637906602;7403.370713489414;5209.6874187548865;5477.1510415885605;5483.314585676791;5485.807996720256;5535.197396377423;8855.557222138996;8967.237784788105;9042.457452552868;9103.54343305068;9190.40262382943;9056.411333263317;9197.126273004054;9200.907990920005;9275.814483710154;9296.163345298955;6705.195376554844;6782.864342713019;6822.317768223138;6836.145481814773;6842.076319696777;7098.929010709827;7104.360607545345;7270.527415717961;7469.775906722807;7520.736774212876;6788.796336109925;6837.863242735018;6875.140607424072;6931.480138989495;7014.579125252501;6728.629925354153;6782.220044694924;6786.558873673195;6912;7000.369966702818;6092.421219984094;6105.877882442415;6115.327313995546;6128.8774224516155;6157.784477543264;4504.504504504436;4521.84173553924;4577.496475387638;4625.93061104077;4668.089722504079;4276.550962148929;4366.19225443294;4378.277584198571;4434.490033646172;4463.174312752151 18:40:17 INFO - PROCESS | 1405 | |4;prototype.html;299.97900146989707;304.1499010266307;304.9176722284983;307.7414971424004;310.5822668510852;4800.783964721587;4812.590929770965;4820.903581928364;4837.186055229289;4849.757512124393;4764.309175169598;4765.976170119145;4771.8091276348905;4772.260299653559;4783.402074740658;3504.281622267429;3609.8195090245513;3626.8186590670493;3726.4596633488127;3767.868124615639;3706.721995850322;3707.3512135376336;3754.530683664542;3761.4921985532014;3762.454444105603;3724.683401910841;3726.105734623698;3733.5146430963937;3734.589195188527;3736.5516138063335;3485.9651403485896;3603.8017909014993;3753.6621704046493;3756.1173124316038;3757.154640205959;3307.338532293527;3313.4201514734823;3322.5846769153854;3323.3353329333986;3329.6836800503916;4655.185342565038;4656.4877863434995;4667.319764884673;4675.462321832964;4677.50886156952;4153.418521407006;4247.8088486018205;4251.553586873396;4252.638525725317;4261.637760790337;4945.554900058976;4958.677685950438;4963.230699241623;4968.515742128936;4972.925406118875;3598.6581274779114;3612.2661122661007;3613.5844377896615;3614.9096272593133;3622.166901612644;4984.950150498485;5205.8958820823755;5216.713080780555;5226.764795584169;5234;3620.9094772630897;3626.6917312028772;3627.2564124354444;3632.636736326346;3640.9089772755365;3681.5398075240596;3681.5766186888313;3682.2451397463456;3682.8710995115043;3687.1335236219466;4224.577542245751;4224.936625950613;4225.830966761357;4234.364845273234;4234.513031001475;4772.546608072227;5009.974950125226;5036.622253331015;5045.899082018339;5049.091163590516;1283.9037072219621;1300.752856957175;1305.7127431964952;1307.2483322089647;1311.9737605248033;810.8378324335157;838.1811469112041;852.9104444033411;867.3451544084176;874.9693760718344;4356.803943822536;4440.2007638625355;4452.977735111303;4470.262406702923;4472.776361181928;5136.1525348317855;5173.52554521967;5179.456157103526;5180.404253510819;5181.974090129525;4431.667624928144;4448.666350023761;4458.152950939311;4477.306017567283;4488.798004089758;2587.9720726749747;2757.5725762506845;2766;2766.7233276672173;2772.4593704227887;4820.638452116105;4843.927341089887;4860.781264843091;4866.46468888422;4875;5087.643864929493;5307.787688492417;5308.92036619451;5332.986732520809;5333.893322133536;4895.531340597878;4896.6572339935865;4907.2393778964315;4912.95087049125;4940.011997600494;5184.170532714747;5196.168613021899;5197.480251974772;5200.323957885451;5250.18622113573;4944.9368385797225;5128.692278463304;5146.356705411824;5149.794008239628;5158.484151584811;4876.390451193601;4904.566803237819;4920.089783389941;4926.211806111005;4930.728809915489;3687.834047467924;3690.7785532868115;3700.3524383233366;3709.1654377764785;3715.461258117504;4947.554720075211;5214.661047031931;5216.556592689664;5220.243064755513;5226.346706661668;5036.471170527188;5055.519725626206;5078.720670363018;5080.949190508048;5081.9745901271735;5029.547340739351;5039.395272567169;5062.746862656926;5072.1123803335;5073.949260507347;4807.206810876166;4935.679180853233;4947.851564453073;4948.901021979612;4961.8511444656715;4829.348038014823;4949.851504454872;5022.974885125551;5053.7473126344275;5089.720065396439;4950.727709975986;4957.652964292465;4963.950360496348;4972.179590367549;4974.701517908937 18:40:17 INFO - PROCESS | 1405 | |5;yui.html;652.7780554611434;657.5890068707057;665.6971078159438;744.2185705009739;746.1866565443665;11622.709432264188;12381.50473981042;12384.133110682256;12396.504139834396;12502.374881255935;12127.939360303208;12134.575289864857;12191.939040304786;13541.864581354184;14052.578422647337;624.9468795152418;779.886916397122;798.9360851131895;799.1528979281951;799.7280924485674;639.0318667219143;766.6396793507042;790.8813677948325;793.2622660925338;794.166125568154;837.874318852171;839.3998291221775;839.4879123734516;840.3487297344545;840.5209030852417;651.1144843013498;666.6266890541312;830.5141492227024;830.543201239322;831.1522247307772;490.26460309535696;490.91409003424616;584.812859884837;602.7739597650881;604.2688347100014;13268.93365533166;13332.133411328232;13356.532521361803;13362.799558006638;13367.933160334136;12103.6368908932;12869.871301286961;12984.28586427746;13021.348932553334;13306;11802.586909458129;11818.704532386673;12002.399880005965;12019.51921923122;12546.937265313707;3992.8003599819895;4036.7174297799165;4165.625093741578;4170.582941705836;4172.853950111762;61443;62426;62578;63229;63432.04851927225;9958.352707074017;10026.498675066217;10228.437435941094;10266.486675666187;10524.052835244865;17247.396341128002;17617.207225674876;17627.118644067745;17659.73510397345;17673.558161046076;6816.522843400914;6856.074429951992;7049.964750176319;7138.500304978602;7244.601546914865;3757.04195430169;3781.716371272166;3851.422286657024;3856.402257650069;3866.38137897935;2906.4187162567573;3016.3967206558336;3016.834074125944;3023.1837403901263;3025.213444504445;505.171518709317;513.3224144129788;520.2898044169681;524.2032111190969;528.0178867306806;511.7824924407112;567.2200724004493;591.2432086928738;592.3632095497358;606.5602438232246;8342.451175826947;9501.667441639509;9846.458444785605;9921.851172232422;10110.39337639744;428.9747503466717;521.9869503262449;541.9024575576434;564.7289301135478;588.1794896119974;3606.549181352331;3841.6287788146838;3877.108265099043;3930.8427662893737;4050.7772072536295;57959;57987.4201257982;57988.36093112541;95240;98734.5063274679;61513.38486615166;85485.57257213673;88139.55930220308;88228.11771882199;88620;46550.53449465462;48547.75726121347;75375.21623053841;75503.24496754962;75507.62246188734;7240.456965727486;7457.962710186414;7563.281488258606;7619.238076192558;7641.656125474478;36781.44827827531;43919.56080439282;44028;44632.553674464136;45471.77264113658;57568.13647795203;64719;65613;66836.6658166706;67138.66430667815;8898.777530561529;9048.41185322952;9853.655122070693;10438.37369757817;10665.8933410668;12606.621801345975;13056.34718264102;13501.797473038108;13872.583822485341;14567.490137845125;12464;12791.616251512469;12951.676208094874;13325.66685832862;13346.866531334563;11332.206745527734;11740.236884602473;11946.462409191781;12517.56188533397;12584;12237.632871013884;12580.308083055153;12859.228446293253;12899;12995.415206315927;9967.38849450179;10369.274150809371;10405.843912341472;10749.570017199538;10932.852100106886;10224.182065434896;10453.895461045597;10492.370457772558;10551.103156231806;10742.086922611354 18:40:17 INFO - PROCESS | 1405 | __end_tp_report 18:40:17 INFO - PROCESS | 1405 | __start_cc_report 18:40:17 INFO - PROCESS | 1405 | _x_x_mozilla_cycle_collect,995 18:40:17 INFO - PROCESS | 1405 | __end_cc_report 18:40:17 INFO - PROCESS | 1405 | __startTimestamp1468633217690__endTimestamp 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | ------- Summary: start ------- 18:40:17 INFO - PROCESS | 1405 | Number of tests: 6 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | [#0] dojo.html Cycles:165 Average:4688.73 Median:4847.73 stddev:616.25 (12.7%) stddev-sans-first:596.36 18:40:17 INFO - PROCESS | 1405 | Values: 2618.6 2620.3 2657.0 2661.0 2679.8 4563.7 4634.3 4804.4 4847.7 4907.4 4711.7 4716.3 4729.5 4739.7 4798.7 4348.0 4367.6 4422.3 4427.8 4441.5 57.4 4425.8 4459.9 4465.2 4490.6 4387.5 4445.8 4452.4 4480.2 4493.8 4325.2 4328.6 4331.8 4370.0 4372.1 4312.5 4316.9 4321.0 4330.4 4375.1 4736.3 4747.1 4827.1 4832.9 4834.7 5113.2 5121.4 5158.9 5190.4 5206.5 5222.6 5229.4 5234.2 5237.7 5240.2 4643.0 4787.5 4808.6 4821.7 4864.2 5289.2 5347.5 5350.3 5356.0 5356.6 5083.5 5097.7 5116.1 5137.3 5137.4 4943.4 5021.2 5059.9 5065.1 5168.6 4967.9 4969.8 4973.8 4999.9 5011.1 4894.9 4941.6 4945.5 4957.2 4997.6 4250.4 4295.0 4298.8 4300.8 4342.0 3795.6 3813.6 3832.7 3844.5 3885.9 4430.3 4432.5 4436.9 4465.4 4477.5 4984.0 5188.8 5203.1 5203.6 5258.8 4290.0 4316.6 4319.7 4334.3 4360.3 4566.8 4570.4 4572.4 4582.9 4605.6 4734.8 4743.2 4746.0 4750.0 4786.4 5014.0 5036.1 5052.4 5057.9 5076.8 4759.2 4764.4 4765.1 4767.2 4807.9 5011.8 5047.3 5048.7 5052.6 5099.8 4966.6 4967.6 4973.0 5001.9 5004.4 4892.0 4892.3 4892.5 4892.9 4945.6 4855.9 4909.0 4916.0 4920.8 4957.5 4915.8 4923.7 4956.2 4969.5 5045.0 5062.3 5095.0 5095.5 5099.1 5143.0 4798.9 4868.0 4993.6 4998.0 5022.5 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | [#1] ext.html Cycles:180 Average:47139.54 Median:203702.13 stddev:82380.04 (40.4%) stddev-sans-first:82536.87 18:40:17 INFO - PROCESS | 1405 | Values: 550.9 575.9 580.0 585.1 595.9 27372.9 39591.6 40477.0 40684.6 40688.4 41546.0 41822.8 41861.2 41890.0 42003.6 7885.1 8105.8 8116.0 8116.3 8118.4 7971.0 8044.1 8044.8 8050.2 8052.2 8057.9 8095.2 8099.0 8100.4 8100.6 8024.8 8056.1 8060.1 8066.7 8068.9 7950.6 7967.6 8007.2 8012.9 8015.4 39614.8 39858.2 40055.4 40071.8 40151.4 2886.4 2919.8 2957.8 3020.9 3059.1 30038.7 30098.8 30490.7 30608.5 31443.8 3233.8 3344.2 3421.6 3465.9 3521.5 255682.0 257648.0 258150.0 259609.0 259914.0 48188.8 49980.3 56086.0 56627.7 56638.7 57133.1 58964.4 59021.7 65643.3 65645.3 15501.8 15608.9 15644.4 15649.7 15650.6 10731.0 11010.3 11650.0 11691.3 11702.2 16022.2 16146.8 17848.5 18268.7 18320.3 6549.2 6700.6 6819.4 7024.8 7057.5 773.4 871.6 884.9 947.6 977.3 28772.7 29017.2 29131.3 29273.0 29730.6 972.1 993.9 1005.2 1044.3 1094.4 14918.0 15280.8 15362.8 15372.4 15563.0 366769.0 369647.0 370425.0 372556.0 373731.0 49513.0 50027.7 50144.7 50252.2 50303.0 1058.5 1156.2 1158.7 1170.5 1208.2 28262.3 29211.0 29272.7 29604.3 30131.4 38739.8 38866.4 38955.8 38963.4 38988.2 297764.0 301830.0 303096.0 303131.0 303925.0 22189.3 22218.3 22560.0 23332.0 27087.2 41993.2 42190.0 42784.1 42826.0 43145.0 35126.5 36446.0 36866.4 36979.3 37105.0 35994.5 36362.6 36399.8 36852.4 37097.3 35971.3 36945.0 38312.6 38372.4 41543.8 31647.7 33864.0 33894.0 34445.7 34592.7 25399.2 26184.9 26393.5 26422.2 26917.7 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | [#2] jquery.html Cycles:180 Average:23815.91 Median:5112.87 stddev:64423.96 (1260.0%) stddev-sans-first:64580.59 18:40:17 INFO - PROCESS | 1405 | Values: 345.6 370.4 372.9 372.9 394.4 12913.4 13504.7 15764.3 17333.8 17368.4 14846.9 15047.8 15051.9 16166.7 16814.4 4896.4 5110.7 5115.1 5118.4 5121.1 3731.1 5687.2 5977.1 5981.3 6068.8 5170.3 6005.8 6012.2 6017.4 6043.8 5436.5 5514.6 5553.6 5560.9 5564.8 5229.7 5329.3 5333.4 5333.8 5415.9 12232.4 12315.1 12409.1 12507.0 12517.7 1885.8 1901.1 1944.9 1953.7 2056.8 8719.2 8873.2 9055.8 9074.1 9088.8 1657.2 1682.9 1724.2 1755.1 1817.8 53262.0 58057.7 69031.0 69452.3 72815.3 9139.8 9477.3 10083.3 10087.5 10149.4 13442.5 13599.6 13608.7 13629.9 13631.4 7389.4 7411.9 7432.7 7457.1 7631.0 7157.8 7212.5 7292.1 7371.9 7372.9 4201.7 4352.7 4367.4 4378.8 4431.7 1773.9 1812.9 1815.6 1844.6 1853.3 793.6 823.5 843.2 891.4 903.6 16244.4 16513.9 16539.2 16656.1 17080.2 868.9 869.0 870.2 899.4 936.9 9143.6 9557.7 9603.7 9616.0 9811.4 382738.0 384235.0 384778.0 398707.0 402506.0 48734.0 56453.0 81836.0 82900.0 82985.0 867.1 887.7 890.3 902.4 904.8 15009.8 15376.0 15431.6 15617.3 15683.0 38302.0 38929.6 39063.6 39635.6 39729.8 52310.5 53247.0 53752.0 54048.9 54448.0 7434.6 7634.9 7669.5 7760.9 7928.2 9793.7 11533.5 11806.1 11878.9 12210.0 9952.0 10138.2 10333.2 10567.2 10769.2 9515.4 9544.3 10218.6 10400.1 10450.3 10446.5 10596.6 10627.8 10661.3 10867.7 6641.7 6827.5 6857.2 6948.2 7744.7 7924.7 9351.9 10110.5 10535.7 10609.6 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | [#3] mootools.html Cycles:180 Average:5127.70 Median:5287.06 stddev:2921.68 (55.3%) stddev-sans-first:2915.48 18:40:17 INFO - PROCESS | 1405 | Values: 1268.0 1379.0 1418.0 1493.7 1716.3 6912.0 7411.4 7668.3 7683.3 7932.5 7591.8 7734.8 7745.0 7746.3 7746.5 2170.5 2202.5 2206.6 2210.2 2214.5 2126.0 2165.1 2184.7 2187.7 2194.4 2150.7 2170.7 2177.0 2178.5 2197.8 2142.9 2143.2 2150.5 2151.2 2151.6 2092.1 2145.8 2146.7 2147.5 2152.2 7698.5 7707.1 7752.7 7881.1 8185.2 4700.8 4816.1 4823.7 4827.1 4831.7 2807.5 2861.8 2914.1 2919.4 2929.9 926.7 930.8 946.5 950.2 951.6 10776.4 10841.0 10865.7 10872.2 10874.9 3965.4 4048.5 4186.5 4200.2 4207.6 8622.1 8677.8 8692.5 8694.7 8713.4 4567.3 4604.3 4611.3 4613.5 4621.0 3615.7 3790.7 3926.5 4286.2 4286.9 3432.9 3542.1 3543.9 3584.3 3592.5 1032.7 1036.8 1045.8 1050.2 1050.4 792.4 794.3 808.4 809.0 818.4 4880.7 4945.5 5066.4 5076.6 5364.4 376.2 889.7 894.9 899.6 934.1 3320.6 3392.6 3394.8 3479.0 3588.5 9693.7 9861.9 9984.3 9994.6 9995.4 9913.8 9931.5 9954.6 9956.9 9986.4 6930.3 7244.5 7257.2 7403.1 7403.4 5209.7 5477.2 5483.3 5485.8 5535.2 8855.6 8967.2 9042.5 9103.5 9190.4 9056.4 9197.1 9200.9 9275.8 9296.2 6705.2 6782.9 6822.3 6836.1 6842.1 7098.9 7104.4 7270.5 7469.8 7520.7 6788.8 6837.9 6875.1 6931.5 7014.6 6728.6 6782.2 6786.6 6912.0 7000.4 6092.4 6105.9 6115.3 6128.9 6157.8 4504.5 4521.8 4577.5 4625.9 4668.1 4276.6 4366.2 4378.3 4434.5 4463.2 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | [#4] prototype.html Cycles:180 Average:4173.71 Median:4792.09 stddev:1213.44 (25.3%) stddev-sans-first:1181.50 18:40:17 INFO - PROCESS | 1405 | Values: 300.0 304.1 304.9 307.7 310.6 4800.8 4812.6 4820.9 4837.2 4849.8 4764.3 4766.0 4771.8 4772.3 4783.4 3504.3 3609.8 3626.8 3726.5 3767.9 3706.7 3707.4 3754.5 3761.5 3762.5 3724.7 3726.1 3733.5 3734.6 3736.6 3486.0 3603.8 3753.7 3756.1 3757.2 3307.3 3313.4 3322.6 3323.3 3329.7 4655.2 4656.5 4667.3 4675.5 4677.5 4153.4 4247.8 4251.6 4252.6 4261.6 4945.6 4958.7 4963.2 4968.5 4972.9 3598.7 3612.3 3613.6 3614.9 3622.2 4985.0 5205.9 5216.7 5226.8 5234.0 3620.9 3626.7 3627.3 3632.6 3640.9 3681.5 3681.6 3682.2 3682.9 3687.1 4224.6 4224.9 4225.8 4234.4 4234.5 4772.5 5010.0 5036.6 5045.9 5049.1 1283.9 1300.8 1305.7 1307.2 1312.0 810.8 838.2 852.9 867.3 875.0 4356.8 4440.2 4453.0 4470.3 4472.8 5136.2 5173.5 5179.5 5180.4 5182.0 4431.7 4448.7 4458.2 4477.3 4488.8 2588.0 2757.6 2766.0 2766.7 2772.5 4820.6 4843.9 4860.8 4866.5 4875.0 5087.6 5307.8 5308.9 5333.0 5333.9 4895.5 4896.7 4907.2 4913.0 4940.0 5184.2 5196.2 5197.5 5200.3 5250.2 4944.9 5128.7 5146.4 5149.8 5158.5 4876.4 4904.6 4920.1 4926.2 4930.7 3687.8 3690.8 3700.4 3709.2 3715.5 4947.6 5214.7 5216.6 5220.2 5226.3 5036.5 5055.5 5078.7 5080.9 5082.0 5029.5 5039.4 5062.7 5072.1 5073.9 4807.2 4935.7 4947.9 4948.9 4961.9 4829.3 4949.9 5023.0 5053.7 5089.7 4950.7 4957.7 4964.0 4972.2 4974.7 18:40:17 INFO - PROCESS | 1405 | 18:40:17 INFO - PROCESS | 1405 | [#5] yui.html Cycles:180 Average:16892.96 Median:45052.16 stddev:23104.27 (51.3%) stddev-sans-first:23136.90 18:40:17 INFO - PROCESS | 1405 | Values: 652.8 657.6 665.7 744.2 746.2 11622.7 12381.5 12384.1 12396.5 12502.4 12127.9 12134.6 12191.9 13541.9 14052.6 624.9 779.9 798.9 799.2 799.7 639.0 766.6 790.9 793.3 794.2 837.9 839.4 839.5 840.3 840.5 651.1 666.6 830.5 830.5 831.2 490.3 490.9 584.8 602.8 604.3 13268.9 13332.1 13356.5 13362.8 13367.9 12103.6 12869.9 12984.3 13021.3 13306.0 11802.6 11818.7 12002.4 12019.5 12546.9 3992.8 4036.7 4165.6 4170.6 4172.9 61443.0 62426.0 62578.0 63229.0 63432.0 9958.4 10026.5 10228.4 10266.5 10524.1 17247.4 17617.2 17627.1 17659.7 17673.6 6816.5 6856.1 7050.0 7138.5 7244.6 3757.0 3781.7 3851.4 3856.4 3866.4 2906.4 3016.4 3016.8 3023.2 3025.2 505.2 513.3 520.3 524.2 528.0 511.8 567.2 591.2 592.4 606.6 8342.5 9501.7 9846.5 9921.9 10110.4 429.0 522.0 541.9 564.7 588.2 3606.5 3841.6 3877.1 3930.8 4050.8 57959.0 57987.4 57988.4 95240.0 98734.5 61513.4 85485.6 88139.6 88228.1 88620.0 46550.5 48547.8 75375.2 75503.2 75507.6 7240.5 7458.0 7563.3 7619.2 7641.7 36781.4 43919.6 44028.0 44632.6 45471.8 57568.1 64719.0 65613.0 66836.7 67138.7 8898.8 9048.4 9853.7 10438.4 10665.9 12606.6 13056.3 13501.8 13872.6 14567.5 12464.0 12791.6 12951.7 13325.7 13346.9 11332.2 11740.2 11946.5 12517.6 12584.0 12237.6 12580.3 12859.2 12899.0 12995.4 9967.4 10369.3 10405.8 10749.6 10932.9 10224.2 10453.9 10492.4 10551.1 10742.1 18:40:17 INFO - PROCESS | 1405 | -------- Summary: end -------- 18:40:17 INFO - PROCESS | 1405 | 18:40:18 INFO - TEST-INFO | 1405: exit 0 18:40:18 INFO - TEST-OK | dromaeo_css | took 1118487ms 18:40:18 INFO - TEST-START | kraken 18:40:18 INFO - operating with platform_type : linux_ 18:40:18 INFO - Initialising browser for kraken test... 18:40:18 INFO - TEST-INFO | started process 1592 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppSGiwA/profile http://localhost:46402/getInfo.html) 18:40:22 INFO - PROCESS | 1592 | __metrics Screen width/height:1600/1200 18:40:22 INFO - PROCESS | 1592 | colorDepth:24 18:40:22 INFO - PROCESS | 1592 | Browser inner width/height: 1024/697 18:40:22 INFO - PROCESS | 1592 | __metrics 18:40:23 INFO - PROCESS | 1592 | 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" 18:40:23 INFO - PROCESS | 1592 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:40:23 INFO - PROCESS | 1592 | console.error: PushService: 18:40:23 INFO - PROCESS | 1592 | stateChangeProcessEnqueue: Error transitioning state 18:40:23 INFO - PROCESS | 1592 | UnknownError 18:40:24 INFO - TEST-INFO | 1592: exit 0 18:40:24 INFO - Browser initialized. 18:40:24 INFO - Running cycle 1/1 for kraken test... 18:40:24 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppSGiwA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:36 INFO - PROCESS | 1674 | RSS: Main: 159358976 18:40:36 INFO - PROCESS | 1674 | 18:40:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46402/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:39 INFO - PROCESS | 1674 | RSS: Main: 218406912 18:40:39 INFO - PROCESS | 1674 | 18:40:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46402/tests/kraken/driver.html?testName=audio-dft) 18:40:43 INFO - PROCESS | 1674 | RSS: Main: 206925824 18:40:43 INFO - PROCESS | 1674 | 18:40:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46402/tests/kraken/driver.html?testName=audio-fft) 18:40:46 INFO - PROCESS | 1674 | RSS: Main: 270819328 18:40:46 INFO - PROCESS | 1674 | 18:40:49 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46402/tests/kraken/driver.html?testName=audio-oscillator) 18:40:49 INFO - PROCESS | 1674 | RSS: Main: 182099968 18:40:49 INFO - PROCESS | 1674 | 18:40:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46402/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:52 INFO - PROCESS | 1674 | RSS: Main: 186548224 18:40:52 INFO - PROCESS | 1674 | 18:40:55 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46402/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:56 INFO - PROCESS | 1674 | RSS: Main: 222605312 18:40:56 INFO - PROCESS | 1674 | 18:40:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46402/tests/kraken/driver.html?testName=imaging-desaturate) 18:41:00 INFO - PROCESS | 1674 | RSS: Main: 218300416 18:41:00 INFO - PROCESS | 1674 | 18:41:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46402/tests/kraken/driver.html?testName=json-parse-financial) 18:41:04 INFO - PROCESS | 1674 | RSS: Main: 221401088 18:41:04 INFO - PROCESS | 1674 | 18:41:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46402/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:41:06 INFO - PROCESS | 1674 | RSS: Main: 209948672 18:41:06 INFO - PROCESS | 1674 | 18:41:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:41:08 INFO - PROCESS | 1674 | RSS: Main: 213196800 18:41:08 INFO - PROCESS | 1674 | 18:41:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:41:11 INFO - PROCESS | 1674 | RSS: Main: 211853312 18:41:11 INFO - PROCESS | 1674 | 18:41:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:41:14 INFO - PROCESS | 1674 | RSS: Main: 225787904 18:41:14 INFO - PROCESS | 1674 | 18:41:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:41:18 INFO - PROCESS | 1674 | RSS: Main: 226095104 18:41:18 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:46402/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46402/tests/kraken/driver.html?testName=ai-astar) 18:41:20 INFO - PROCESS | 1674 | RSS: Main: 269037568 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | __start_tp_report 18:41:20 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 18:41:20 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 18:41:20 INFO - PROCESS | 1674 | |i|pagename|runs| 18:41:20 INFO - PROCESS | 1674 | |0;ai-astar;107;118;115;117;114;116;113;114;114;116 18:41:20 INFO - PROCESS | 1674 | |1;audio-beat-detection;152;124;129;177;122;126;152;122;184;126 18:41:20 INFO - PROCESS | 1674 | |2;audio-dft;160;162;161;160;162;163;162;162;162;161 18:41:20 INFO - PROCESS | 1674 | |3;audio-fft;76;76;77;119;77;77;74;76;109;75 18:41:20 INFO - PROCESS | 1674 | |4;audio-oscillator;85;84;138;84;85;85;122;85;84;141 18:41:20 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;117;117;117;117;117;117;118;116;117;117 18:41:20 INFO - PROCESS | 1674 | |6;imaging-darkroom;146;147;147;147;146;146;148;147;146;146 18:41:20 INFO - PROCESS | 1674 | |7;imaging-desaturate;95;95;94;95;94;95;95;98;95;95 18:41:20 INFO - PROCESS | 1674 | |8;json-parse-financial;63;62;61;61;61;63;61;60;61;62 18:41:20 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;65;66;66;67;66;65;66;65;65;65 18:41:20 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;79;80;78;81;78;79;78;79;87;88 18:41:20 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;128;130;126;132;132;127;127;130;164;137 18:41:20 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;163;162;163;161;164;163;163;163;168;163 18:41:20 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;67;66;67;66;67;66;70;66;67;66 18:41:20 INFO - PROCESS | 1674 | __end_tp_report 18:41:20 INFO - PROCESS | 1674 | __start_cc_report 18:41:20 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1887 18:41:20 INFO - PROCESS | 1674 | __end_cc_report 18:41:20 INFO - PROCESS | 1674 | __startTimestamp1468633280404__endTimestamp 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | ------- Summary: start ------- 18:41:20 INFO - PROCESS | 1674 | Number of tests: 14 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.50 stddev:3.03 (2.6%) stddev-sans-first:1.64 18:41:20 INFO - PROCESS | 1674 | Values: 107.0 118.0 115.0 117.0 114.0 116.0 113.0 114.0 114.0 116.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:141.40 Median:140.50 stddev:23.55 (16.8%) stddev-sans-first:24.66 18:41:20 INFO - PROCESS | 1674 | Values: 152.0 124.0 129.0 177.0 122.0 126.0 152.0 122.0 184.0 126.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:0.97 (0.6%) stddev-sans-first:0.87 18:41:20 INFO - PROCESS | 1674 | Values: 160.0 162.0 161.0 160.0 162.0 163.0 162.0 162.0 162.0 161.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.00 stddev:16.22 (21.3%) stddev-sans-first:16.97 18:41:20 INFO - PROCESS | 1674 | Values: 76.0 76.0 77.0 119.0 77.0 77.0 74.0 76.0 109.0 75.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:99.30 Median:84.50 stddev:24.20 (28.6%) stddev-sans-first:25.11 18:41:20 INFO - PROCESS | 1674 | Values: 85.0 84.0 138.0 84.0 85.0 85.0 122.0 85.0 84.0 141.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.47 (0.4%) stddev-sans-first:0.50 18:41:20 INFO - PROCESS | 1674 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 118.0 116.0 117.0 117.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:146.60 Median:147.00 stddev:0.70 (0.5%) stddev-sans-first:0.71 18:41:20 INFO - PROCESS | 1674 | Values: 146.0 147.0 147.0 147.0 146.0 146.0 148.0 147.0 146.0 146.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:1.10 (1.2%) stddev-sans-first:1.17 18:41:20 INFO - PROCESS | 1674 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 95.0 98.0 95.0 95.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:61.50 Median:61.50 stddev:0.97 (1.6%) stddev-sans-first:0.87 18:41:20 INFO - PROCESS | 1674 | Values: 63.0 62.0 61.0 61.0 61.0 63.0 61.0 60.0 61.0 62.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:65.60 Median:66.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 18:41:20 INFO - PROCESS | 1674 | Values: 65.0 66.0 66.0 67.0 66.0 65.0 66.0 65.0 65.0 65.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:79.50 stddev:3.71 (4.7%) stddev-sans-first:3.89 18:41:20 INFO - PROCESS | 1674 | Values: 79.0 80.0 78.0 81.0 78.0 79.0 78.0 79.0 87.0 88.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:133.30 Median:131.00 stddev:11.26 (8.6%) stddev-sans-first:11.78 18:41:20 INFO - PROCESS | 1674 | Values: 128.0 130.0 126.0 132.0 132.0 127.0 127.0 130.0 164.0 137.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.30 Median:163.00 stddev:1.83 (1.1%) stddev-sans-first:1.94 18:41:20 INFO - PROCESS | 1674 | Values: 163.0 162.0 163.0 161.0 164.0 163.0 163.0 163.0 168.0 163.0 18:41:20 INFO - PROCESS | 1674 | 18:41:20 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:1.23 (1.8%) stddev-sans-first:1.30 18:41:20 INFO - PROCESS | 1674 | Values: 67.0 66.0 67.0 66.0 67.0 66.0 70.0 66.0 67.0 66.0 18:41:20 INFO - PROCESS | 1674 | -------- Summary: end -------- 18:41:20 INFO - PROCESS | 1674 | 18:41:21 INFO - TEST-INFO | 1674: exit 0 18:41:21 INFO - TEST-OK | kraken | took 63035ms 18:41:21 INFO - SUITE-END | took 1181s 18:41:21 INFO - Completed test suite (00:19:41) 18:41:21 INFO - javascript benchmark 18:41:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2618.5941179117235, 2620.2663254288805, 2657.0301839828467, 2660.960085598716, 2679.7588217060475, 4563.7489938053395, 4634.304854271861, 4804.399450068741, 4847.733374664392, 4907.362042934418, 4711.693739906904, 4716.268978308359, 4729.503402142777, 4739.73931433771, 4798.712077275366, 4348.043430445313, 4367.563243675623, 4422.336649502568, 4427.8228870845205, 4441.5336389679105, 57.37853691422246, 4425.77871106445, 4459.888502787424, 4465.218586747323, 4490.640748740109, 4387.473503179607, 4445.777711114431, 4452.376667266585, 4480.215962206634, 4493.842715504941, 4325.221460137174, 4328.61042506176, 4331.848385306531, 4369.978150109229, 4372.1255748850035, 4312.482502099737, 4316.892077698051, 4320.956790432118, 4330.415393921811, 4375.0812329410865, 4736.313234580966, 4747.145513807513, 4827.08285425768, 4832.855014349575, 4834.709917404932, 5113.2330150477355, 5121.359830021247, 5158.92261616076, 5190.429052804227, 5206.531412172884, 5222.608304377187, 5229.398619158807, 5234.241035049897, 5237.685738855643, 5240.187770895517, 4643.024964757439, 4787.473377928425, 4808.61531077513, 4821.710697358169, 4864.1974074277705, 5289.153735402318, 5347.465253474621, 5350.304460420199, 5355.955588660174, 5356.571474282048, 5083.466236045162, 5097.668651537638, 5116.079105760924, 5137.255098010767, 5137.434882162959, 4943.406791185081, 5021.171506701354, 5059.8988020239385, 5065.062963351792, 5168.560672342893, 4967.850964471072, 4969.826056088092, 4973.825916092919, 4999.850004499871, 5011.123053465629, 4894.94758626897, 4941.579965702884, 4945.455999840015, 4957.182064959241, 4997.625178111656, 4250.447441832542, 4294.957050429456, 4298.78506074695, 4300.827966881352, 4342.044750154961, 3795.5635101963076, 3813.56144043433, 3832.6550610444638, 3844.5386553613184, 3885.902852428712, 4430.269005614037, 4432.534583868647, 4436.866893993185, 4465.397171381886, 4477.462704475419, 4984.028114517707, 5188.792448302026, 5203.14148165556, 5203.5576975957465, 5258.842234732964, 4289.978550107292, 4316.568343165658, 4319.697621166488, 4334.306510958295, 4360.345948107746, 4566.794494247767, 4570.428696412949, 4572.4055872736335, 4582.908341833145, 4605.585497305258, 4734.786934587952, 4743.241081426955, 4746.0, 4750.002499475149, 4786.425628924551, 5014.0, 5036.143855544493, 5052.418971818141, 5057.949420505748, 5076.82231121909, 4759.1671457495495, 4764.380630518011, 4765.0946320199055, 4767.237242041257, 4807.927881081857, 5011.84964451067, 5047.2681461187185, 5048.697078175174, 5052.577201809927, 5099.847004589868, 4966.602671786176, 4967.627427942991, 4972.950270497248, 5001.899962000813, 5004.42449118356, 4891.9755401222765, 4892.2661600760175, 4892.461829198714, 4892.877678058077, 4945.579625731853, 4855.902881942412, 4908.969116485435, 4915.95084049155, 4920.82777102814, 4957.479464656303, 4915.778789954389, 4923.6799608026795, 4956.157453232887, 4969.503049695001, 5045.041442125985, 5062.291279220985, 5095.0, 5095.464976177596, 5099.082165210298, 5143.0, 4798.880027999328, 4868.026394720999, 4993.600511958962, 4997.975010125072, 5022.547970682656], "value": 4652.218465718339, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [550.9083539079486, 575.907854743241, 579.9565032622552, 585.1135529672549, 595.9016762234231, 27372.863135684318, 39591.60408395919, 40477.0, 40684.59315406845, 40688.38967415491, 41546.0, 41822.7908860456, 41861.162776744524, 41890.0, 42003.57996420035, 7885.053793544768, 8105.756827295161, 8115.959420202891, 8116.310113640318, 8118.431709780318, 7971.0, 8044.115147333789, 8044.8391032179325, 8050.194980501961, 8052.235037671441, 8057.879131813028, 8095.230953059479, 8099.0, 8100.432969692124, 8100.554469504175, 8024.839503209932, 8056.073551541531, 8060.113387527367, 8066.717664881701, 8068.919310806876, 7950.602469876483, 7967.641456134488, 8007.239312265326, 8012.879806802903, 8015.398845086584, 39614.801925990185, 39858.20283594312, 40055.39916901249, 40071.7996410019, 40151.397729034084, 2886.3938572899715, 2919.8102123362128, 2957.7929544931862, 3020.894268700585, 3059.143439836849, 30038.699613004028, 30098.84950575255, 30490.695093049228, 30608.54087188694, 31443.842780785948, 3233.8059716416856, 3344.2475443025123, 3421.606515250753, 3465.9133521661906, 3521.506989021539, 255682.0, 257648.0, 258150.0, 259609.0, 259914.0, 48188.75905620449, 49980.250296245584, 56086.0, 56627.71686141543, 56638.7168064157, 57133.14300285499, 58964.41035589589, 59021.70489147527, 65643.34356656468, 65645.34354656392, 15501.767473487907, 15608.921955390377, 15644.374225031099, 15649.687006259812, 15650.608734781721, 10731.0, 11010.284331518425, 11650.0, 11691.298522088702, 11702.239354441934, 16022.19889005545, 16146.75779863303, 17848.46454606364, 18268.725969110474, 18320.26718931228, 6549.214094308713, 6700.564463309869, 6819.420349270268, 7024.789256322217, 7057.541259818096, 773.3928865840288, 871.5598622695625, 884.8849649545518, 947.6493697332031, 977.3256453047517, 28772.712272877423, 29017.227760391015, 29131.271718207216, 29273.0, 29730.554041689393, 972.1202311907734, 993.8906720260766, 1005.1857995024054, 1044.2690116918188, 1094.414488248799, 14918.0, 15280.847191528164, 15362.769558456632, 15372.385104595915, 15563.0, 366769.0, 369647.0, 370425.0, 372556.0, 373731.0, 49513.0, 50027.74986125119, 50144.74927625339, 50252.24621630678, 50303.0, 1058.5289546152042, 1156.1848896527927, 1158.7219067423757, 1170.5200867644396, 1208.1965492947093, 28262.293442664097, 29211.0, 29272.707272926997, 29604.259893502836, 30131.397372052874, 38739.80630096832, 38866.41700374553, 38955.805220973714, 38963.415548767356, 38988.2202355957, 297764.0, 301830.0, 303096.0, 303131.0, 303925.0, 22189.334319970425, 22218.333449996528, 22560.0, 23332.0, 27087.1873843785, 41993.160136797705, 42190.0, 42784.14431711411, 42826.0, 43145.0, 35126.47310290399, 36446.0, 36866.44700329444, 36979.260414792094, 37105.0, 35994.4600830993, 36362.63637363593, 36399.81800090983, 36852.447213292355, 37097.258054839294, 35971.28057438784, 36945.0, 38312.616873830906, 38372.42441363438, 41543.79228103961, 31647.683523164473, 33864.0, 33894.0, 34445.65554344424, 34592.65407345894, 25399.238022859343, 26184.8690756545, 26393.472130557664, 26422.207333780018, 26917.730822691523], "value": 17736.529079572436, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.6146396767605, 370.353732736375, 372.92355067211236, 372.92727918055954, 394.37885330604286, 12913.354332283396, 13504.729905401886, 15764.290606922674, 17333.82666173338, 17368.392106276282, 14846.925765371157, 15047.84952150481, 15051.92474037631, 16166.676666466694, 16814.411495597655, 4896.387951506062, 5110.693358398489, 5115.053715062707, 5118.360204974379, 5121.078205922932, 3731.1045349116152, 5687.203791469197, 5977.133315669225, 5981.252343457068, 6068.757249710006, 5170.2503137045105, 6005.759769609211, 6012.188354572142, 6017.398260173992, 6043.788467403641, 5436.537894278991, 5514.558835293207, 5553.583481238883, 5560.888782224373, 5564.8330550083565, 5229.660072095302, 5329.307190065267, 5333.4399888011585, 5333.839984800462, 5415.864603384947, 12232.388380580935, 12315.137940344179, 12409.131360804748, 12507.0, 12517.749645007048, 1885.754851869262, 1901.1064799544192, 1944.8541359398102, 1953.7167110769, 2056.845736569748, 8719.171678690578, 8873.245774109208, 9055.818883622356, 9074.13795689415, 9088.77278068047, 1657.1631326180323, 1682.9326826926908, 1724.1551639696459, 1755.1265687623238, 1817.8475055534261, 53262.01927874352, 58057.70971145117, 69030.96453553201, 69452.30547694508, 72815.27184728191, 9139.77150571241, 9477.336586439022, 10083.336249518894, 10087.49562521871, 10149.441780701985, 13442.462301508025, 13599.59201223965, 13608.727825443435, 13629.931850340685, 13631.386587603582, 7389.371903388164, 7411.88882166768, 7432.665530051161, 7457.105147382349, 7631.046119235096, 7157.785266441911, 7212.4951253411755, 7292.124945006633, 7371.88942165868, 7372.889406658905, 4201.705880588391, 4352.73883566987, 4367.366731823868, 4378.78106094694, 4431.667624928144, 1773.893566386021, 1812.9456116316533, 1815.6005678750653, 1844.5665268661853, 1853.33280019193, 793.5516433215215, 823.5429336718059, 843.1821133500495, 891.4027601507006, 903.6475774447971, 16244.431444899372, 16513.91743041277, 16539.173041347884, 16656.08391538477, 17080.23138958725, 868.887044684187, 869.0483920107472, 870.1950695606539, 899.4288626721948, 936.866391666078, 9143.634254629873, 9557.665481708107, 9603.663871764455, 9616.0, 9811.362261452983, 382738.0, 384235.0, 384778.0, 398707.0, 402506.0, 48734.0, 56453.0, 81836.0, 82900.0, 82985.0, 867.0520231213868, 887.658251573149, 890.2610833008588, 902.4089221559889, 904.7873749668822, 15009.77485337699, 15376.0, 15431.61420964485, 15617.297221624827, 15683.0, 38302.0, 38929.6107038926, 39063.609363906, 39635.60364396319, 39729.80135099306, 52310.47689523056, 53247.0, 53752.0, 54048.920205068294, 54448.0, 7434.59109748969, 7634.923650763421, 7669.501482403849, 7760.922390776246, 7928.246816552418, 9793.657221997197, 11533.480993355151, 11806.1145414096, 11878.881211187778, 12210.0, 9952.0, 10138.23963202748, 10333.173346132142, 10567.207459440726, 10769.246152769545, 9515.382384994706, 9544.284178686488, 10218.642347518098, 10400.063994240554, 10450.320729152581, 10446.477676116316, 10596.629117981141, 10627.840582391116, 10661.307015044307, 10867.728306792393, 6641.734330626914, 6827.48793840454, 6857.245702972768, 6948.166220053626, 7744.728934487266, 7924.683012679428, 9351.906480935104, 10110.49447527606, 10535.683929482127, 10609.575616975235], "value": 8030.487515019207, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.0, 1378.9862101378983, 1418.0286503744933, 1493.6564590144267, 1716.3134746101566, 6911.965440172805, 7411.3700335471485, 7668.309852113309, 7683.270089341504, 7932.484388514757, 7591.848163036736, 7734.7679569612865, 7744.9612751936165, 7746.341560967325, 7746.457747957646, 2170.4573856535867, 2202.548477562104, 2206.58074965756, 2210.171185805323, 2214.512807182425, 2125.968110478344, 2165.069020321261, 2184.7050648162535, 2187.671849222613, 2194.440417693486, 2150.7204063471804, 2170.70695456113, 2176.967345489819, 2178.5425060737266, 2197.8021978021975, 2142.935711928637, 2143.217725530186, 2150.505383761728, 2151.2470635277527, 2151.6234658934623, 2092.1003968293626, 2145.753238377591, 2146.6994620753107, 2147.5490147069136, 2152.1929276521305, 7698.499597526143, 7707.075150981862, 7752.651130699132, 7881.133075361706, 8185.222403871623, 4700.764961751899, 4816.133096042711, 4823.710577365334, 4827.106985207748, 4831.661783675144, 2807.5227211374117, 2861.785366097551, 2914.0966300446717, 2919.4015226878437, 2929.8974535891352, 926.684927124774, 930.7719608695845, 946.5456580841166, 950.1686024728363, 951.6050838901847, 10776.40729759863, 10841.0, 10865.728356791064, 10872.238943273975, 10874.945625271823, 3965.385365268388, 4048.4939382577177, 4186.455760751144, 4200.180964711913, 4207.600277973587, 8622.051574326819, 8677.783055423539, 8692.478451292942, 8694.652213911499, 8713.390062695551, 4567.2920697291975, 4604.309353596987, 4611.308303754464, 4613.515580864028, 4621.0295837873755, 3615.7468977171347, 3790.7346485746284, 3926.541320507237, 4286.207051695384, 4286.935695964563, 3432.9313413731584, 3542.1498840278146, 3543.94684079739, 3584.318979393907, 3592.497050412944, 1032.7108409645311, 1036.7874585710063, 1045.7751583409604, 1050.2438244463974, 1050.3645294596658, 792.4334101117613, 794.2811755361407, 808.3977436809614, 809.0170442911773, 818.4271010292819, 4880.682755620874, 4945.505449455026, 5066.36670416198, 5076.6446348755235, 5364.436734142937, 376.17992775749127, 889.7019498467956, 894.8836651235428, 899.6086702284528, 934.1265916368218, 3320.6181289151573, 3392.5759280089987, 3394.813285269334, 3479.043263102667, 3588.4617307404096, 9693.660721874841, 9861.852072218922, 9984.251181161442, 9994.550245238981, 9995.350302230332, 9913.752156196153, 9931.503424828874, 9954.601815927426, 9956.900430995742, 9986.400815951067, 6930.30696930313, 7244.45666575009, 7257.201707812136, 7403.148637906602, 7403.370713489414, 5209.6874187548865, 5477.1510415885605, 5483.314585676791, 5485.807996720256, 5535.197396377423, 8855.557222138996, 8967.237784788105, 9042.457452552868, 9103.54343305068, 9190.40262382943, 9056.411333263317, 9197.126273004054, 9200.907990920005, 9275.814483710154, 9296.163345298955, 6705.195376554844, 6782.864342713019, 6822.317768223138, 6836.145481814773, 6842.076319696777, 7098.929010709827, 7104.360607545345, 7270.527415717961, 7469.775906722807, 7520.736774212876, 6788.796336109925, 6837.863242735018, 6875.140607424072, 6931.480138989495, 7014.579125252501, 6728.629925354153, 6782.220044694924, 6786.558873673195, 6912.0, 7000.369966702818, 6092.421219984094, 6105.877882442415, 6115.327313995546, 6128.8774224516155, 6157.784477543264, 4504.504504504436, 4521.84173553924, 4577.496475387638, 4625.93061104077, 4668.089722504079, 4276.550962148929, 4366.19225443294, 4378.277584198571, 4434.490033646172, 4463.174312752151], "value": 4097.6826282431775, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.97900146989707, 304.1499010266307, 304.9176722284983, 307.7414971424004, 310.5822668510852, 4800.783964721587, 4812.590929770965, 4820.903581928364, 4837.186055229289, 4849.757512124393, 4764.309175169598, 4765.976170119145, 4771.8091276348905, 4772.260299653559, 4783.402074740658, 3504.281622267429, 3609.8195090245513, 3626.8186590670493, 3726.4596633488127, 3767.868124615639, 3706.721995850322, 3707.3512135376336, 3754.530683664542, 3761.4921985532014, 3762.454444105603, 3724.683401910841, 3726.105734623698, 3733.5146430963937, 3734.589195188527, 3736.5516138063335, 3485.9651403485896, 3603.8017909014993, 3753.6621704046493, 3756.1173124316038, 3757.154640205959, 3307.338532293527, 3313.4201514734823, 3322.5846769153854, 3323.3353329333986, 3329.6836800503916, 4655.185342565038, 4656.4877863434995, 4667.319764884673, 4675.462321832964, 4677.50886156952, 4153.418521407006, 4247.8088486018205, 4251.553586873396, 4252.638525725317, 4261.637760790337, 4945.554900058976, 4958.677685950438, 4963.230699241623, 4968.515742128936, 4972.925406118875, 3598.6581274779114, 3612.2661122661007, 3613.5844377896615, 3614.9096272593133, 3622.166901612644, 4984.950150498485, 5205.8958820823755, 5216.713080780555, 5226.764795584169, 5234.0, 3620.9094772630897, 3626.6917312028772, 3627.2564124354444, 3632.636736326346, 3640.9089772755365, 3681.5398075240596, 3681.5766186888313, 3682.2451397463456, 3682.8710995115043, 3687.1335236219466, 4224.577542245751, 4224.936625950613, 4225.830966761357, 4234.364845273234, 4234.513031001475, 4772.546608072227, 5009.974950125226, 5036.622253331015, 5045.899082018339, 5049.091163590516, 1283.9037072219621, 1300.752856957175, 1305.7127431964952, 1307.2483322089647, 1311.9737605248033, 810.8378324335157, 838.1811469112041, 852.9104444033411, 867.3451544084176, 874.9693760718344, 4356.803943822536, 4440.2007638625355, 4452.977735111303, 4470.262406702923, 4472.776361181928, 5136.1525348317855, 5173.52554521967, 5179.456157103526, 5180.404253510819, 5181.974090129525, 4431.667624928144, 4448.666350023761, 4458.152950939311, 4477.306017567283, 4488.798004089758, 2587.9720726749747, 2757.5725762506845, 2766.0, 2766.7233276672173, 2772.4593704227887, 4820.638452116105, 4843.927341089887, 4860.781264843091, 4866.46468888422, 4875.0, 5087.643864929493, 5307.787688492417, 5308.92036619451, 5332.986732520809, 5333.893322133536, 4895.531340597878, 4896.6572339935865, 4907.2393778964315, 4912.95087049125, 4940.011997600494, 5184.170532714747, 5196.168613021899, 5197.480251974772, 5200.323957885451, 5250.18622113573, 4944.9368385797225, 5128.692278463304, 5146.356705411824, 5149.794008239628, 5158.484151584811, 4876.390451193601, 4904.566803237819, 4920.089783389941, 4926.211806111005, 4930.728809915489, 3687.834047467924, 3690.7785532868115, 3700.3524383233366, 3709.1654377764785, 3715.461258117504, 4947.554720075211, 5214.661047031931, 5216.556592689664, 5220.243064755513, 5226.346706661668, 5036.471170527188, 5055.519725626206, 5078.720670363018, 5080.949190508048, 5081.9745901271735, 5029.547340739351, 5039.395272567169, 5062.746862656926, 5072.1123803335, 5073.949260507347, 4807.206810876166, 4935.679180853233, 4947.851564453073, 4948.901021979612, 4961.8511444656715, 4829.348038014823, 4949.851504454872, 5022.974885125551, 5053.7473126344275, 5089.720065396439, 4950.727709975986, 4957.652964292465, 4963.950360496348, 4972.179590367549, 4974.701517908937], "value": 3790.7391472475088, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.7780554611434, 657.5890068707057, 665.6971078159438, 744.2185705009739, 746.1866565443665, 11622.709432264188, 12381.50473981042, 12384.133110682256, 12396.504139834396, 12502.374881255935, 12127.939360303208, 12134.575289864857, 12191.939040304786, 13541.864581354184, 14052.578422647337, 624.9468795152418, 779.886916397122, 798.9360851131895, 799.1528979281951, 799.7280924485674, 639.0318667219143, 766.6396793507042, 790.8813677948325, 793.2622660925338, 794.166125568154, 837.874318852171, 839.3998291221775, 839.4879123734516, 840.3487297344545, 840.5209030852417, 651.1144843013498, 666.6266890541312, 830.5141492227024, 830.543201239322, 831.1522247307772, 490.26460309535696, 490.91409003424616, 584.812859884837, 602.7739597650881, 604.2688347100014, 13268.93365533166, 13332.133411328232, 13356.532521361803, 13362.799558006638, 13367.933160334136, 12103.6368908932, 12869.871301286961, 12984.28586427746, 13021.348932553334, 13306.0, 11802.586909458129, 11818.704532386673, 12002.399880005965, 12019.51921923122, 12546.937265313707, 3992.8003599819895, 4036.7174297799165, 4165.625093741578, 4170.582941705836, 4172.853950111762, 61443.0, 62426.0, 62578.0, 63229.0, 63432.04851927225, 9958.352707074017, 10026.498675066217, 10228.437435941094, 10266.486675666187, 10524.052835244865, 17247.396341128002, 17617.207225674876, 17627.118644067745, 17659.73510397345, 17673.558161046076, 6816.522843400914, 6856.074429951992, 7049.964750176319, 7138.500304978602, 7244.601546914865, 3757.04195430169, 3781.716371272166, 3851.422286657024, 3856.402257650069, 3866.38137897935, 2906.4187162567573, 3016.3967206558336, 3016.834074125944, 3023.1837403901263, 3025.213444504445, 505.171518709317, 513.3224144129788, 520.2898044169681, 524.2032111190969, 528.0178867306806, 511.7824924407112, 567.2200724004493, 591.2432086928738, 592.3632095497358, 606.5602438232246, 8342.451175826947, 9501.667441639509, 9846.458444785605, 9921.851172232422, 10110.39337639744, 428.9747503466717, 521.9869503262449, 541.9024575576434, 564.7289301135478, 588.1794896119974, 3606.549181352331, 3841.6287788146838, 3877.108265099043, 3930.8427662893737, 4050.7772072536295, 57959.0, 57987.4201257982, 57988.36093112541, 95240.0, 98734.5063274679, 61513.38486615166, 85485.57257213673, 88139.55930220308, 88228.11771882199, 88620.0, 46550.53449465462, 48547.75726121347, 75375.21623053841, 75503.24496754962, 75507.62246188734, 7240.456965727486, 7457.962710186414, 7563.281488258606, 7619.238076192558, 7641.656125474478, 36781.44827827531, 43919.56080439282, 44028.0, 44632.553674464136, 45471.77264113658, 57568.13647795203, 64719.0, 65613.0, 66836.6658166706, 67138.66430667815, 8898.777530561529, 9048.41185322952, 9853.655122070693, 10438.37369757817, 10665.8933410668, 12606.621801345975, 13056.34718264102, 13501.797473038108, 13872.583822485341, 14567.490137845125, 12464.0, 12791.616251512469, 12951.676208094874, 13325.66685832862, 13346.866531334563, 11332.206745527734, 11740.236884602473, 11946.462409191781, 12517.56188533397, 12584.0, 12237.632871013884, 12580.308083055153, 12859.228446293253, 12899.0, 12995.415206315927, 9967.38849450179, 10369.274150809371, 10405.843912341472, 10749.570017199538, 10932.852100106886, 10224.182065434896, 10453.895461045597, 10492.370457772558, 10551.103156231806, 10742.086922611354], "value": 6542.778127566685, "unit": "score"}], "name": "dromaeo_css", "value": 6378.5832659432845, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 115.0, 117.0, 114.0, 116.0, 113.0, 114.0, 114.0, 116.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 129.0, 177.0, 122.0, 126.0, 152.0, 122.0, 184.0, 126.0], "value": 141.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 161.0, 160.0, 162.0, 163.0, 162.0, 162.0, 162.0, 161.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 77.0, 119.0, 77.0, 77.0, 74.0, 76.0, 109.0, 75.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 138.0, 84.0, 85.0, 85.0, 122.0, 85.0, 84.0, 141.0], "value": 99.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 116.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 146.0, 146.0, 148.0, 147.0, 146.0, 146.0], "value": 146.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 98.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 61.0, 61.0, 61.0, 63.0, 61.0, 60.0, 61.0, 62.0], "value": 61.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 66.0, 65.0, 66.0, 65.0, 65.0, 65.0], "value": 65.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 78.0, 81.0, 78.0, 79.0, 78.0, 79.0, 87.0, 88.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 130.0, 126.0, 132.0, 132.0, 127.0, 127.0, 130.0, 164.0, 137.0], "value": 133.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 162.0, 163.0, 161.0, 164.0, 163.0, 163.0, 163.0, 168.0, 163.0], "value": 163.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 66.0, 67.0, 66.0, 70.0, 66.0, 67.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1530.1, "alertThreshold": 2.0}]} 18:41:21 INFO - Return code: 0 18:41:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:41:21 INFO - # TBPL SUCCESS # 18:41:21 INFO - Running post-action listener: _resource_record_post_action 18:41:21 INFO - ##### 18:41:21 INFO - ##### Finished run-tests step (success) 18:41:21 INFO - ##### 18:41:21 INFO - Running post-run listener: _resource_record_post_run 18:41:21 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 0; Write bytes: 540254208; Read time: 0; Write time: 415280 18:41:21 INFO - TinderboxPrint: CPU usage
13.0% 18:41:21 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 18:41:21 INFO - TinderboxPrint: I/O write bytes / time
540,254,208 / 415,280 18:41:21 INFO - TinderboxPrint: CPU idle
8,322.7 (87.0%) 18:41:21 INFO - TinderboxPrint: CPU user
1,198.1 (12.5%) 18:41:21 INFO - TinderboxPrint: Swap in / out
0 / 0 18:41:21 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 256909312; Read time: 0; Write time: 250728 18:41:21 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 283344896; Read time: 0; Write time: 164552 18:41:22 INFO - Running post-run listener: _upload_blobber_files 18:41:22 INFO - Blob upload gear active. 18:41:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:22 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 18:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:41:22 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:26 INFO - (blobuploader) - INFO - Done attempting. 18:41:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:41:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:41:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:27 INFO - (blobuploader) - INFO - Done attempting. 18:41:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:41:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:41:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:28 INFO - (blobuploader) - INFO - Done attempting. 18:41:28 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:28 INFO - Return code: 0 18:41:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/158f23fffeb3fb8d7b635adce24594f2165ad6334b1207d19fcc4c23c191c7812c908b6a7fc1d510d7597f08427c7aca93cd4ae249fa8f7ba6f43aa17d38cfb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c52e6e060b9d7eac6e968b6c0ededd47de0d1f25b6a71f4e2acb58d9b4d81ecdb0f8d8f521b006c9b1276e67bf56f924257ad6a1dd01fc1da202994dff3cf7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f4391ec2564b8e3df9f65551dcdab5b2c061bceec73401a7d21ecff77a65252f78d0d3e4fbc9c10dafbca042947d6f6bef861896c8e5c48bcb6affb88b85cfe"} 18:41:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:28 INFO - Contents: 18:41:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/158f23fffeb3fb8d7b635adce24594f2165ad6334b1207d19fcc4c23c191c7812c908b6a7fc1d510d7597f08427c7aca93cd4ae249fa8f7ba6f43aa17d38cfb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c52e6e060b9d7eac6e968b6c0ededd47de0d1f25b6a71f4e2acb58d9b4d81ecdb0f8d8f521b006c9b1276e67bf56f924257ad6a1dd01fc1da202994dff3cf7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f4391ec2564b8e3df9f65551dcdab5b2c061bceec73401a7d21ecff77a65252f78d0d3e4fbc9c10dafbca042947d6f6bef861896c8e5c48bcb6affb88b85cfe"} 18:41:28 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:28 INFO - Copying logs to upload dir... 18:41:28 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1237.376819 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 37 secs) (at 2016-07-15 18:41:27.668224) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 18:41:27.674992) ========= 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=523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/158f23fffeb3fb8d7b635adce24594f2165ad6334b1207d19fcc4c23c191c7812c908b6a7fc1d510d7597f08427c7aca93cd4ae249fa8f7ba6f43aa17d38cfb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c52e6e060b9d7eac6e968b6c0ededd47de0d1f25b6a71f4e2acb58d9b4d81ecdb0f8d8f521b006c9b1276e67bf56f924257ad6a1dd01fc1da202994dff3cf7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f4391ec2564b8e3df9f65551dcdab5b2c061bceec73401a7d21ecff77a65252f78d0d3e4fbc9c10dafbca042947d6f6bef861896c8e5c48bcb6affb88b85cfe"} build_url:https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011321 build_url: 'https://queue.taskcluster.net/v1/task/CVZtsMUcS3y6p2XqovIpNQ/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/Firefox-Non-PGO/sha512/158f23fffeb3fb8d7b635adce24594f2165ad6334b1207d19fcc4c23c191c7812c908b6a7fc1d510d7597f08427c7aca93cd4ae249fa8f7ba6f43aa17d38cfb4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/4c52e6e060b9d7eac6e968b6c0ededd47de0d1f25b6a71f4e2acb58d9b4d81ecdb0f8d8f521b006c9b1276e67bf56f924257ad6a1dd01fc1da202994dff3cf7f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/5f4391ec2564b8e3df9f65551dcdab5b2c061bceec73401a7d21ecff77a65252f78d0d3e4fbc9c10dafbca042947d6f6bef861896c8e5c48bcb6affb88b85cfe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 18:41:27.704012) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:41:27.704366) ========= 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=523ca41ca1e0623de333ad52000001bf-1468632047.940166-1207132653 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004456 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 18:41:27.786739) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 18:41:27.787101) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 18:41:27.787397) ========= ========= Total master_lag: 0.74 =========