builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-033 starttime: 1468364898.16 results: success (0) buildid: 20160712145029 builduid: 2a7a1a2877914428a1793bcedbe1bfc1 revision: b4484a7069fbc7b6131fa370d96301b00a57b9e8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.160414) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.160898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.161223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.258065) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.258409) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-12 16:08:18-- 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% 227M=0s 2016-07-12 16:08:18 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155836 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.458353) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.458671) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015919 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.510003) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:18.510371) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b4484a7069fbc7b6131fa370d96301b00a57b9e8 --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 b4484a7069fbc7b6131fa370d96301b00a57b9e8 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-12 16:08:18,519 truncating revision to first 12 chars 2016-07-12 16:08:18,519 Setting DEBUG logging. 2016-07-12 16:08:18,519 attempt 1/10 2016-07-12 16:08:18,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b4484a7069fb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-12 16:08:18,887 unpacking tar archive at: mozilla-central-b4484a7069fb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.731790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:19.261423) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:19.261754) ========= script_repo_revision: b4484a7069fbc7b6131fa370d96301b00a57b9e8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:19.262210) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:19.262565) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-12 16:08:19.280353) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-12 16:08:19.280664) ========= /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=edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:08:19 INFO - MultiFileLogger online at 20160712 16:08:19 in /builds/slave/test 16:08:19 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 16:08:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:08:19 INFO - {'append_to_log': False, 16:08:19 INFO - 'base_work_dir': '/builds/slave/test', 16:08:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:08:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:08:19 INFO - 'branch': 'Firefox-Non-PGO', 16:08:19 INFO - 'buildbot_json_path': 'buildprops.json', 16:08:19 INFO - 'config_files': ('talos/linux_config.py',), 16:08:19 INFO - 'default_actions': ('clobber', 16:08:19 INFO - 'read-buildbot-config', 16:08:19 INFO - 'download-and-extract', 16:08:19 INFO - 'populate-webroot', 16:08:19 INFO - 'create-virtualenv', 16:08:19 INFO - 'install', 16:08:19 INFO - 'run-tests'), 16:08:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:08:19 INFO - 'download_minidump_stackwalk': True, 16:08:19 INFO - 'download_symbols': 'ondemand', 16:08:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:08:19 INFO - 'tooltool.py': '/tools/tooltool.py', 16:08:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:08:19 INFO - '/tools/misc-python/virtualenv.py')}, 16:08:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:08:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:08:19 INFO - 'installer_path': 'installer.exe', 16:08:19 INFO - 'log_level': 'info', 16:08:19 INFO - 'log_name': 'talos', 16:08:19 INFO - 'log_to_console': True, 16:08:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:08:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:08:19 INFO - 'opt_config_files': (), 16:08:19 INFO - 'pip_index': False, 16:08:19 INFO - 'sps_profile': False, 16:08:19 INFO - 'sps_profile_interval': 0, 16:08:19 INFO - 'suite': 'dromaeojs', 16:08:19 INFO - 'system_bits': '32', 16:08:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:08:19 INFO - 'title': 'talos-linux64-ix-033', 16:08:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:08:19 INFO - 'use_talos_json': True, 16:08:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:08:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:08:19 INFO - 'work_dir': 'build'} 16:08:19 INFO - ##### 16:08:19 INFO - ##### Running clobber step. 16:08:19 INFO - ##### 16:08:19 INFO - Running pre-action listener: _resource_record_pre_action 16:08:19 INFO - Running main action method: clobber 16:08:19 INFO - rmtree: /builds/slave/test/build 16:08:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:08:20 INFO - Running post-action listener: _resource_record_post_action 16:08:20 INFO - ##### 16:08:20 INFO - ##### Finished clobber step (success) 16:08:20 INFO - ##### 16:08:20 INFO - ##### 16:08:20 INFO - ##### Running read-buildbot-config step. 16:08:20 INFO - ##### 16:08:20 INFO - Running pre-action listener: _resource_record_pre_action 16:08:20 INFO - Running main action method: read_buildbot_config 16:08:20 INFO - Using buildbot properties: 16:08:20 INFO - { 16:08:20 INFO - "project": "", 16:08:20 INFO - "product": "firefox", 16:08:20 INFO - "script_repo_revision": "production", 16:08:20 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:08:20 INFO - "repository": "", 16:08:20 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:08:20 INFO - "buildid": "20160712145029", 16:08:20 INFO - "pgo_build": "False", 16:08:20 INFO - "basedir": "/builds/slave/test", 16:08:20 INFO - "buildnumber": 556, 16:08:20 INFO - "slavename": "talos-linux64-ix-033", 16:08:20 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 16:08:20 INFO - "platform": "ubuntu64_hw", 16:08:20 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:08:20 INFO - "branch": "mozilla-central", 16:08:20 INFO - "repo_path": "mozilla-central", 16:08:20 INFO - "revision": "b4484a7069fbc7b6131fa370d96301b00a57b9e8", 16:08:20 INFO - "stage_platform": "linux64", 16:08:20 INFO - "builduid": "2a7a1a2877914428a1793bcedbe1bfc1", 16:08:20 INFO - "slavebuilddir": "test" 16:08:20 INFO - } 16:08:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:08:20 INFO - Running post-action listener: _resource_record_post_action 16:08:20 INFO - ##### 16:08:20 INFO - ##### Finished read-buildbot-config step (success) 16:08:20 INFO - ##### 16:08:20 INFO - ##### 16:08:20 INFO - ##### Running download-and-extract step. 16:08:20 INFO - ##### 16:08:20 INFO - Running pre-action listener: _resource_record_pre_action 16:08:20 INFO - Running main action method: download_and_extract 16:08:20 INFO - mkdir: /builds/slave/test/build/tests 16:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:20 INFO - https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:08:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:20 INFO - Downloaded 1361 bytes. 16:08:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:08:20 INFO - Using the following test package requirements: 16:08:20 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:20 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:08:20 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:08:20 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'jsshell-linux-x86_64.zip'], 16:08:20 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:08:20 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:08:20 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:08:20 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:08:20 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:08:20 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:08:20 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:08:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:08:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:20 INFO - https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:21 INFO - Downloaded 22700490 bytes. 16:08:21 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'] 16:08:21 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 16:08:23 INFO - Return code: 0 16:08:23 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 16:08:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:23 INFO - https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:08:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:08:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:25 INFO - Downloaded 22700490 bytes. 16:08:25 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'] 16:08:25 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 16:08:26 INFO - Return code: 0 16:08:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:26 INFO - https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:08:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:08:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:27 INFO - Downloaded 14403317 bytes. 16:08:27 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'] 16:08:27 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 16:08:28 INFO - Return code: 0 16:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:08:28 INFO - https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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 16:08:29 INFO - Downloaded 56659183 bytes. 16:08:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:29 INFO - mkdir: /builds/slave/test/properties 16:08:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:08:29 INFO - Writing to file /builds/slave/test/properties/build_url 16:08:29 INFO - Contents: 16:08:29 INFO - build_url:https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:08:30 INFO - Running post-action listener: _resource_record_post_action 16:08:30 INFO - Running post-action listener: set_extra_try_arguments 16:08:30 INFO - ##### 16:08:30 INFO - ##### Finished download-and-extract step (success) 16:08:30 INFO - ##### 16:08:30 INFO - ##### 16:08:30 INFO - ##### Running populate-webroot step. 16:08:30 INFO - ##### 16:08:30 INFO - Running pre-action listener: _resource_record_pre_action 16:08:30 INFO - Running main action method: populate_webroot 16:08:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:08:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:08:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:08:30 INFO - '--tppagecycles', 16:08:30 INFO - '7'], 16:08:30 INFO - 'tests': ['tsvgm']}}, 16:08:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:08:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:08:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:08:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:08:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:08:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['damp', 'tps']}, 16:08:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'tests': ['damp', 'tps']}, 16:08:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['dromaeo_dom']}, 16:08:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:08:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['basic_compositor_video']}, 16:08:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:08:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['a11yr', 16:08:30 INFO - 'ts_paint', 16:08:30 INFO - 'tpaint', 16:08:30 INFO - 'sessionrestore', 16:08:30 INFO - 'sessionrestore_no_auto_restore', 16:08:30 INFO - 'tabpaint']}, 16:08:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tsvgx', 16:08:30 INFO - 'tsvgr_opacity', 16:08:30 INFO - 'tart', 16:08:30 INFO - 'tscrollx', 16:08:30 INFO - 'cart']}, 16:08:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:08:30 INFO - 'tsvgr_opacity', 16:08:30 INFO - 'tart', 16:08:30 INFO - 'tscrollx', 16:08:30 INFO - 'cart']}, 16:08:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s'], 16:08:30 INFO - 'tests': ['tp5o']}, 16:08:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'tests': ['tp5o']}, 16:08:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--disable-e10s', 16:08:30 INFO - '--xperf_path', 16:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:30 INFO - 'tests': ['tp5n']}, 16:08:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:08:30 INFO - 'talos_options': ['--xperf_path', 16:08:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:08:30 INFO - 'tests': ['tp5n']}}, 16:08:30 INFO - 'talos.zip': {'path': '', 16:08:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:08:30 INFO - Running post-action listener: _resource_record_post_action 16:08:30 INFO - ##### 16:08:30 INFO - ##### Finished populate-webroot step (success) 16:08:30 INFO - ##### 16:08:30 INFO - ##### 16:08:30 INFO - ##### Running create-virtualenv step. 16:08:30 INFO - ##### 16:08:30 INFO - Running pre-action listener: _resource_record_pre_action 16:08:30 INFO - Running main action method: create_virtualenv 16:08:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:08:30 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 16:08:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:08:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:08:31 INFO - Using real prefix '/usr' 16:08:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:08:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:08:33 INFO - Installing pip.................done. 16:08:33 INFO - Return code: 0 16:08:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:33 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:33 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 16:08:33 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 16:08:33 INFO - Using env: {'DISPLAY': ':0', 16:08:33 INFO - 'HOME': '/home/cltbld', 16:08:33 INFO - 'LANG': 'en_US.UTF-8', 16:08:33 INFO - 'LANGUAGE': 'en_US:en', 16:08:33 INFO - 'LOGNAME': 'cltbld', 16:08:33 INFO - 'MAIL': '/var/mail/cltbld', 16:08:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:33 INFO - 'MOZ_NO_REMOTE': '1', 16:08:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:33 INFO - 'NO_EM_RESTART': '1', 16:08:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:33 INFO - 'PWD': '/builds/slave/test', 16:08:33 INFO - 'SHELL': '/bin/bash', 16:08:33 INFO - 'SHLVL': '1', 16:08:33 INFO - 'TERM': 'linux', 16:08:33 INFO - 'TMOUT': '86400', 16:08:33 INFO - 'USER': 'cltbld', 16:08:33 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:33 INFO - '_': '/tools/buildbot/bin/python'} 16:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:34 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 16:08:34 INFO - Cleaning up... 16:08:34 INFO - Return code: 0 16:08:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:08:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:08:34 INFO - Using env: {'DISPLAY': ':0', 16:08:34 INFO - 'HOME': '/home/cltbld', 16:08:34 INFO - 'LANG': 'en_US.UTF-8', 16:08:34 INFO - 'LANGUAGE': 'en_US:en', 16:08:34 INFO - 'LOGNAME': 'cltbld', 16:08:34 INFO - 'MAIL': '/var/mail/cltbld', 16:08:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:34 INFO - 'MOZ_NO_REMOTE': '1', 16:08:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:34 INFO - 'NO_EM_RESTART': '1', 16:08:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:34 INFO - 'PWD': '/builds/slave/test', 16:08:34 INFO - 'SHELL': '/bin/bash', 16:08:34 INFO - 'SHLVL': '1', 16:08:34 INFO - 'TERM': 'linux', 16:08:34 INFO - 'TMOUT': '86400', 16:08:34 INFO - 'USER': 'cltbld', 16:08:34 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:34 INFO - '_': '/tools/buildbot/bin/python'} 16:08:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:34 INFO - Downloading/unpacking psutil>=3.1.1 16:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:08:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:08:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:08:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:08:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:36 INFO - Installing collected packages: psutil 16:08:36 INFO - Running setup.py install for psutil 16:08:37 INFO - building 'psutil._psutil_linux' extension 16:08:37 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 16:08:38 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 16:08:38 INFO - building 'psutil._psutil_posix' extension 16:08:38 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 16:08:38 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 16:08:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:08:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:08:38 INFO - Successfully installed psutil 16:08:38 INFO - Cleaning up... 16:08:38 INFO - Return code: 0 16:08:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:08:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:08:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:38 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:38 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 16:08:38 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 16:08:38 INFO - Using env: {'DISPLAY': ':0', 16:08:38 INFO - 'HOME': '/home/cltbld', 16:08:38 INFO - 'LANG': 'en_US.UTF-8', 16:08:38 INFO - 'LANGUAGE': 'en_US:en', 16:08:38 INFO - 'LOGNAME': 'cltbld', 16:08:38 INFO - 'MAIL': '/var/mail/cltbld', 16:08:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:38 INFO - 'MOZ_NO_REMOTE': '1', 16:08:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:38 INFO - 'NO_EM_RESTART': '1', 16:08:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:38 INFO - 'PWD': '/builds/slave/test', 16:08:38 INFO - 'SHELL': '/bin/bash', 16:08:38 INFO - 'SHLVL': '1', 16:08:38 INFO - 'TERM': 'linux', 16:08:38 INFO - 'TMOUT': '86400', 16:08:38 INFO - 'USER': 'cltbld', 16:08:38 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:38 INFO - '_': '/tools/buildbot/bin/python'} 16:08:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:08:39 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 16:08:39 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 16:08:39 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 16:08:39 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 16:08:39 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 16:08:39 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 16:08:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:08:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:08:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:08:41 INFO - Installing collected packages: mozsystemmonitor 16:08:41 INFO - Running setup.py install for mozsystemmonitor 16:08:41 INFO - Successfully installed mozsystemmonitor 16:08:41 INFO - Cleaning up... 16:08:41 INFO - Return code: 0 16:08:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:08:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:08:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:41 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:41 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 16:08:41 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 16:08:41 INFO - Using env: {'DISPLAY': ':0', 16:08:41 INFO - 'HOME': '/home/cltbld', 16:08:41 INFO - 'LANG': 'en_US.UTF-8', 16:08:41 INFO - 'LANGUAGE': 'en_US:en', 16:08:41 INFO - 'LOGNAME': 'cltbld', 16:08:41 INFO - 'MAIL': '/var/mail/cltbld', 16:08:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:41 INFO - 'MOZ_NO_REMOTE': '1', 16:08:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:41 INFO - 'NO_EM_RESTART': '1', 16:08:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:41 INFO - 'PWD': '/builds/slave/test', 16:08:41 INFO - 'SHELL': '/bin/bash', 16:08:41 INFO - 'SHLVL': '1', 16:08:41 INFO - 'TERM': 'linux', 16:08:41 INFO - 'TMOUT': '86400', 16:08:41 INFO - 'USER': 'cltbld', 16:08:41 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:41 INFO - '_': '/tools/buildbot/bin/python'} 16:08:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:41 INFO - Downloading/unpacking jsonschema==2.5.1 16:08:41 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 16:08:41 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 16:08:41 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 16:08:41 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 16:08:41 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 16:08:41 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 16:08:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:08:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:08:43 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 16:08:43 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 16:08:43 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 16:08:43 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 16:08:43 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 16:08:43 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 16:08:43 INFO - Downloading functools32-3.2.3-2.tar.gz 16:08:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:08:43 INFO - warning: no files found matching '*.txt' 16:08:43 INFO - no previously-included directories found matching 'build' 16:08:43 INFO - no previously-included directories found matching 'dist' 16:08:43 INFO - no previously-included directories found matching '.git*' 16:08:43 INFO - Installing collected packages: jsonschema, functools32 16:08:43 INFO - Running setup.py install for functools32 16:08:43 INFO - warning: no files found matching '*.txt' 16:08:43 INFO - no previously-included directories found matching 'build' 16:08:43 INFO - no previously-included directories found matching 'dist' 16:08:43 INFO - no previously-included directories found matching '.git*' 16:08:43 INFO - Successfully installed jsonschema functools32 16:08:43 INFO - Cleaning up... 16:08:43 INFO - Return code: 0 16:08:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:08:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:08:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:08:43 INFO - Using env: {'DISPLAY': ':0', 16:08:43 INFO - 'HOME': '/home/cltbld', 16:08:43 INFO - 'LANG': 'en_US.UTF-8', 16:08:43 INFO - 'LANGUAGE': 'en_US:en', 16:08:43 INFO - 'LOGNAME': 'cltbld', 16:08:43 INFO - 'MAIL': '/var/mail/cltbld', 16:08:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:43 INFO - 'MOZ_NO_REMOTE': '1', 16:08:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:43 INFO - 'NO_EM_RESTART': '1', 16:08:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:43 INFO - 'PWD': '/builds/slave/test', 16:08:43 INFO - 'SHELL': '/bin/bash', 16:08:43 INFO - 'SHLVL': '1', 16:08:43 INFO - 'TERM': 'linux', 16:08:43 INFO - 'TMOUT': '86400', 16:08:43 INFO - 'USER': 'cltbld', 16:08:43 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:43 INFO - '_': '/tools/buildbot/bin/python'} 16:08:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:08:44 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 16:08:44 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 16:08:44 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 16:08:44 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 16:08:44 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 16:08:44 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 16:08:45 INFO - Downloading blobuploader-1.2.4.tar.gz 16:08:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:08:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:08:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 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 16:08:46 INFO - Downloading docopt-0.6.1.tar.gz 16:08:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:08:46 INFO - Installing collected packages: blobuploader, requests, docopt 16:08:46 INFO - Running setup.py install for blobuploader 16:08:46 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:08:46 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:08:46 INFO - Running setup.py install for requests 16:08:47 INFO - Running setup.py install for docopt 16:08:47 INFO - Successfully installed blobuploader requests docopt 16:08:47 INFO - Cleaning up... 16:08:47 INFO - Return code: 0 16:08:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:47 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')]} 16:08:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:47 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')]} 16:08:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:47 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:47 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 16:08:47 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 16:08:47 INFO - Using env: {'DISPLAY': ':0', 16:08:47 INFO - 'HOME': '/home/cltbld', 16:08:47 INFO - 'LANG': 'en_US.UTF-8', 16:08:47 INFO - 'LANGUAGE': 'en_US:en', 16:08:47 INFO - 'LOGNAME': 'cltbld', 16:08:47 INFO - 'MAIL': '/var/mail/cltbld', 16:08:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:47 INFO - 'MOZ_NO_REMOTE': '1', 16:08:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:47 INFO - 'NO_EM_RESTART': '1', 16:08:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:47 INFO - 'PWD': '/builds/slave/test', 16:08:47 INFO - 'SHELL': '/bin/bash', 16:08:47 INFO - 'SHLVL': '1', 16:08:47 INFO - 'TERM': 'linux', 16:08:47 INFO - 'TMOUT': '86400', 16:08:47 INFO - 'USER': 'cltbld', 16:08:47 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:47 INFO - '_': '/tools/buildbot/bin/python'} 16:08:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:47 INFO - Running setup.py (path:/tmp/pip-bWCen3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:47 INFO - Running setup.py (path:/tmp/pip-DCFXuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:47 INFO - Running setup.py (path:/tmp/pip-gabj1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:47 INFO - Running setup.py (path:/tmp/pip-cI0rh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:47 INFO - Running setup.py (path:/tmp/pip-gqbwF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:47 INFO - Running setup.py (path:/tmp/pip-7GzRfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:48 INFO - Running setup.py (path:/tmp/pip-FFS7Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:48 INFO - Running setup.py (path:/tmp/pip-vPULaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:48 INFO - Running setup.py (path:/tmp/pip-wuJmFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:48 INFO - Running setup.py (path:/tmp/pip-SIIQwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:48 INFO - Running setup.py (path:/tmp/pip-6prpMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:48 INFO - Running setup.py (path:/tmp/pip-REuaRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:48 INFO - Running setup.py (path:/tmp/pip-jBADw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:48 INFO - Running setup.py (path:/tmp/pip-GsPbvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:48 INFO - Running setup.py (path:/tmp/pip-XUm_57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:48 INFO - Running setup.py (path:/tmp/pip-wimyyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:48 INFO - Running setup.py (path:/tmp/pip-NMV4ck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:08:48 INFO - Running setup.py install for manifestparser 16:08:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:08:48 INFO - Running setup.py install for mozcrash 16:08:48 INFO - Running setup.py install for mozdebug 16:08:49 INFO - Running setup.py install for mozdevice 16:08:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Running setup.py install for mozfile 16:08:49 INFO - Running setup.py install for mozhttpd 16:08:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Running setup.py install for mozinfo 16:08:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Running setup.py install for mozInstall 16:08:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Running setup.py install for mozleak 16:08:49 INFO - Running setup.py install for mozlog 16:08:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Running setup.py install for moznetwork 16:08:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:08:49 INFO - Running setup.py install for mozprocess 16:08:50 INFO - Running setup.py install for mozprofile 16:08:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:08:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:08:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:08:50 INFO - Running setup.py install for mozrunner 16:08:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:08:50 INFO - Running setup.py install for mozscreenshot 16:08:50 INFO - Running setup.py install for moztest 16:08:50 INFO - Running setup.py install for mozversion 16:08:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:08:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:08:50 INFO - Cleaning up... 16:08:50 INFO - Return code: 0 16:08:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:50 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')]} 16:08:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:50 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')]} 16:08:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:50 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:50 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 16:08:50 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 16:08:50 INFO - Using env: {'DISPLAY': ':0', 16:08:50 INFO - 'HOME': '/home/cltbld', 16:08:50 INFO - 'LANG': 'en_US.UTF-8', 16:08:50 INFO - 'LANGUAGE': 'en_US:en', 16:08:50 INFO - 'LOGNAME': 'cltbld', 16:08:50 INFO - 'MAIL': '/var/mail/cltbld', 16:08:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:50 INFO - 'MOZ_NO_REMOTE': '1', 16:08:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:50 INFO - 'NO_EM_RESTART': '1', 16:08:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:50 INFO - 'PWD': '/builds/slave/test', 16:08:50 INFO - 'SHELL': '/bin/bash', 16:08:50 INFO - 'SHLVL': '1', 16:08:50 INFO - 'TERM': 'linux', 16:08:50 INFO - 'TMOUT': '86400', 16:08:50 INFO - 'USER': 'cltbld', 16:08:50 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:50 INFO - '_': '/tools/buildbot/bin/python'} 16:08:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:08:50 INFO - Running setup.py (path:/tmp/pip-Y3tqYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:08:50 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)) 16:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:08:50 INFO - Running setup.py (path:/tmp/pip-Tide56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:08:50 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)) 16:08:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:08:50 INFO - Running setup.py (path:/tmp/pip-_jsUjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:08:51 INFO - Running setup.py (path:/tmp/pip-YTZ1aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:08:51 INFO - Running setup.py (path:/tmp/pip-ixhEyQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:08:51 INFO - Running setup.py (path:/tmp/pip-n1PTMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:08:51 INFO - Running setup.py (path:/tmp/pip-0P5_Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:08:51 INFO - Running setup.py (path:/tmp/pip-dB3KmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:08:51 INFO - Running setup.py (path:/tmp/pip-vrAZee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:08:51 INFO - Running setup.py (path:/tmp/pip-faSgfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:08:51 INFO - Running setup.py (path:/tmp/pip-LYgC79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:08:51 INFO - Running setup.py (path:/tmp/pip-fXj6AA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:08:51 INFO - Running setup.py (path:/tmp/pip-Biwj3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:08:51 INFO - Running setup.py (path:/tmp/pip-ijfuJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:51 INFO - Running setup.py (path:/tmp/pip-NJPTc5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:08:51 INFO - Running setup.py (path:/tmp/pip-k0Wzyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:08:51 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)) 16:08:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:08:51 INFO - Running setup.py (path:/tmp/pip-cwCXvV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:08:52 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)) 16:08:52 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)) 16:08:52 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)) 16:08:52 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)) 16:08:52 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)) 16:08:52 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)) 16:08:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:08:52 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 16:08:52 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 16:08:52 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 16:08:52 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 16:08:52 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 16:08:52 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 16:08:53 INFO - Downloading blessings-1.6.tar.gz 16:08:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:08:54 INFO - Installing collected packages: blessings 16:08:54 INFO - Running setup.py install for blessings 16:08:54 INFO - Successfully installed blessings 16:08:54 INFO - Cleaning up... 16:08:54 INFO - Return code: 0 16:08:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:54 INFO - Reading from file tmpfile_stdout 16:08:54 INFO - Current package versions: 16:08:54 INFO - argparse == 1.2.1 16:08:54 INFO - blessings == 1.6 16:08:54 INFO - blobuploader == 1.2.4 16:08:54 INFO - docopt == 0.6.1 16:08:54 INFO - functools32 == 3.2.3-2 16:08:54 INFO - jsonschema == 2.5.1 16:08:54 INFO - manifestparser == 1.1 16:08:54 INFO - mozInstall == 1.12 16:08:54 INFO - mozcrash == 0.17 16:08:54 INFO - mozdebug == 0.1 16:08:54 INFO - mozdevice == 0.48 16:08:54 INFO - mozfile == 1.2 16:08:54 INFO - mozhttpd == 0.7 16:08:54 INFO - mozinfo == 0.9 16:08:54 INFO - mozleak == 0.1 16:08:54 INFO - mozlog == 3.2 16:08:54 INFO - moznetwork == 0.27 16:08:54 INFO - mozprocess == 0.23 16:08:54 INFO - mozprofile == 0.28 16:08:54 INFO - mozrunner == 6.12 16:08:54 INFO - mozscreenshot == 0.1 16:08:54 INFO - mozsystemmonitor == 0.3 16:08:54 INFO - moztest == 0.7 16:08:54 INFO - mozversion == 1.4 16:08:54 INFO - psutil == 3.1.1 16:08:54 INFO - requests == 1.2.3 16:08:54 INFO - wsgiref == 0.1.2 16:08:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:08:54 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')]} 16:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:54 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')]} 16:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:54 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:54 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 16:08:54 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 16:08:54 INFO - Using env: {'DISPLAY': ':0', 16:08:54 INFO - 'HOME': '/home/cltbld', 16:08:54 INFO - 'LANG': 'en_US.UTF-8', 16:08:54 INFO - 'LANGUAGE': 'en_US:en', 16:08:54 INFO - 'LOGNAME': 'cltbld', 16:08:54 INFO - 'MAIL': '/var/mail/cltbld', 16:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:54 INFO - 'MOZ_NO_REMOTE': '1', 16:08:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:54 INFO - 'NO_EM_RESTART': '1', 16:08:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:54 INFO - 'PWD': '/builds/slave/test', 16:08:54 INFO - 'SHELL': '/bin/bash', 16:08:54 INFO - 'SHLVL': '1', 16:08:54 INFO - 'TERM': 'linux', 16:08:54 INFO - 'TMOUT': '86400', 16:08:54 INFO - 'USER': 'cltbld', 16:08:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:54 INFO - '_': '/tools/buildbot/bin/python'} 16:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 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)) 16:08:54 INFO - Cleaning up... 16:08:54 INFO - Return code: 0 16:08:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:08:54 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')]} 16:08:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:08:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:54 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')]} 16:08:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:08:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:08:54 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 0x25af030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27128a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2870180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', '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 16:08:54 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 16:08:54 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 16:08:54 INFO - Using env: {'DISPLAY': ':0', 16:08:54 INFO - 'HOME': '/home/cltbld', 16:08:54 INFO - 'LANG': 'en_US.UTF-8', 16:08:54 INFO - 'LANGUAGE': 'en_US:en', 16:08:54 INFO - 'LOGNAME': 'cltbld', 16:08:54 INFO - 'MAIL': '/var/mail/cltbld', 16:08:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:08:54 INFO - 'MOZ_NO_REMOTE': '1', 16:08:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:08:54 INFO - 'NO_EM_RESTART': '1', 16:08:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:08:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:08:54 INFO - 'PWD': '/builds/slave/test', 16:08:54 INFO - 'SHELL': '/bin/bash', 16:08:54 INFO - 'SHLVL': '1', 16:08:54 INFO - 'TERM': 'linux', 16:08:54 INFO - 'TMOUT': '86400', 16:08:54 INFO - 'USER': 'cltbld', 16:08:54 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:08:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:08:54 INFO - '_': '/tools/buildbot/bin/python'} 16:08:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:08:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:08:54 INFO - Cleaning up... 16:08:54 INFO - Return code: 0 16:08:54 INFO - Running post-action listener: _resource_record_post_action 16:08:54 INFO - Running post-action listener: _start_resource_monitoring 16:08:54 INFO - Starting resource monitoring. 16:08:54 INFO - ##### 16:08:54 INFO - ##### Finished create-virtualenv step (success) 16:08:54 INFO - ##### 16:08:54 INFO - ##### 16:08:54 INFO - ##### Running install step. 16:08:54 INFO - ##### 16:08:54 INFO - Running pre-action listener: _resource_record_pre_action 16:08:54 INFO - Running main action method: install 16:08:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:08:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:08:55 INFO - Reading from file tmpfile_stdout 16:08:55 INFO - Detecting whether we're running mozinstall >=1.0... 16:08:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:08:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:08:55 INFO - Reading from file tmpfile_stdout 16:08:55 INFO - Output received: 16:08:55 INFO - Usage: mozinstall [options] installer 16:08:55 INFO - Options: 16:08:55 INFO - -h, --help show this help message and exit 16:08:55 INFO - -d DEST, --destination=DEST 16:08:55 INFO - Directory to install application into. [default: 16:08:55 INFO - "/builds/slave/test"] 16:08:55 INFO - --app=APP Application being installed. [default: firefox] 16:08:55 INFO - mkdir: /builds/slave/test/build/application 16:08:55 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'] 16:08:55 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 16:09:11 INFO - Reading from file tmpfile_stdout 16:09:11 INFO - Output received: 16:09:11 INFO - /builds/slave/test/build/application/firefox/firefox 16:09:11 INFO - Running post-action listener: _resource_record_post_action 16:09:11 INFO - ##### 16:09:11 INFO - ##### Finished install step (success) 16:09:11 INFO - ##### 16:09:11 INFO - ##### 16:09:11 INFO - ##### Running run-tests step. 16:09:11 INFO - ##### 16:09:11 INFO - Running pre-action listener: _resource_record_pre_action 16:09:11 INFO - Running main action method: run_tests 16:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:09:11 INFO - Python 2.7.3 16:09:11 INFO - Return code: 0 16:09:11 INFO - grabbing minidump binary from tooltool 16:09: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')]} 16:09:11 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 0x286e3b0>, '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 0x286f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x286f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:09:11 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 16:09:11 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 16:09:11 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 16:09:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:09:11 INFO - Return code: 0 16:09:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:09:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:09:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:09:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:09:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:09:11 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/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:09:11 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/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-033 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:09:11 INFO - Using env: {'DISPLAY': ':0', 16:09:11 INFO - 'HOME': '/home/cltbld', 16:09:11 INFO - 'LANG': 'en_US.UTF-8', 16:09:11 INFO - 'LANGUAGE': 'en_US:en', 16:09:11 INFO - 'LOGNAME': 'cltbld', 16:09:11 INFO - 'MAIL': '/var/mail/cltbld', 16:09:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:09:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:11 INFO - 'MOZ_NO_REMOTE': '1', 16:09:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:09:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:09:11 INFO - 'NO_EM_RESTART': '1', 16:09:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:11 INFO - 'PWD': '/builds/slave/test', 16:09:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:09:11 INFO - 'SHELL': '/bin/bash', 16:09:11 INFO - 'SHLVL': '1', 16:09:11 INFO - 'TERM': 'linux', 16:09:11 INFO - 'TMOUT': '86400', 16:09:11 INFO - 'USER': 'cltbld', 16:09:11 INFO - 'XDG_SESSION_COOKIE': 'edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673', 16:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:11 INFO - '_': '/tools/buildbot/bin/python'} 16:09:11 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/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-033', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:09:12 INFO - mozversion application_buildid: 20160712145029 16:09:12 INFO - mozversion application_changeset: b4484a7069fbc7b6131fa370d96301b00a57b9e8 16:09:12 INFO - mozversion application_display_name: Nightly 16:09:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:09:12 INFO - mozversion application_name: Firefox 16:09:12 INFO - mozversion application_remotingname: firefox 16:09:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:09:12 INFO - mozversion application_vendor: Mozilla 16:09:12 INFO - mozversion application_version: 50.0a1 16:09:12 INFO - mozversion platform_buildid: 20160712145029 16:09:12 INFO - mozversion platform_changeset: b4484a7069fbc7b6131fa370d96301b00a57b9e8 16:09:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:09:12 INFO - mozversion platform_version: 50.0a1 16:09:12 INFO - using testdate: 1468364952 16:09:12 INFO - actual date: 1468364952 16:09:12 INFO - starting webserver on 'localhost:45349' 16:09:12 INFO - SUITE-START | Running 2 tests 16:09:12 INFO - TEST-START | dromaeo_css 16:09:12 INFO - operating with platform_type : linux_ 16:09:12 INFO - Initialising browser for dromaeo_css test... 16:09:12 INFO - TEST-INFO | started process 3299 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj9HfkB/profile http://localhost:45349/getInfo.html) 16:09:19 INFO - PROCESS | 3299 | __metrics Screen width/height:1600/1200 16:09:19 INFO - PROCESS | 3299 | colorDepth:24 16:09:19 INFO - PROCESS | 3299 | Browser inner width/height: 1024/697 16:09:19 INFO - PROCESS | 3299 | __metrics 16:09:20 INFO - PROCESS | 3299 | 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" 16:09:20 INFO - PROCESS | 3299 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:09:21 INFO - TEST-INFO | 3299: exit 0 16:09:21 INFO - Browser initialized. 16:09:21 INFO - Running cycle 1/1 for dromaeo_css test... 16:09:21 INFO - TEST-INFO | started process 3380 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpj9HfkB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:09:33 INFO - PROCESS | 3380 | RSS: Main: 158978048 16:09:33 INFO - PROCESS | 3380 | 16:10:25 INFO - PROCESS | 3380 | 1468365025019 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] 16:12:37 INFO - PROCESS | 3380 | Cycle 1(1): loaded http://localhost:45349/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45349/tests/dromaeo/cssquery-ext.html) 16:12:38 INFO - PROCESS | 3380 | RSS: Main: 254803968 16:12:38 INFO - PROCESS | 3380 | 16:15:40 INFO - PROCESS | 3380 | Cycle 1(1): loaded http://localhost:45349/tests/dromaeo/cssquery-ext.html (next: http://localhost:45349/tests/dromaeo/cssquery-jquery.html) 16:15:41 INFO - PROCESS | 3380 | RSS: Main: 248262656 16:15:41 INFO - PROCESS | 3380 | 16:18:43 INFO - PROCESS | 3380 | Cycle 1(1): loaded http://localhost:45349/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45349/tests/dromaeo/cssquery-mootools.html) 16:18:44 INFO - PROCESS | 3380 | RSS: Main: 244551680 16:18:44 INFO - PROCESS | 3380 | 16:21:46 INFO - PROCESS | 3380 | Cycle 1(1): loaded http://localhost:45349/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45349/tests/dromaeo/cssquery-prototype.html) 16:21:47 INFO - PROCESS | 3380 | RSS: Main: 238903296 16:21:47 INFO - PROCESS | 3380 | 16:24:50 INFO - PROCESS | 3380 | Cycle 1(1): loaded http://localhost:45349/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45349/tests/dromaeo/cssquery-yui.html) 16:24:50 INFO - PROCESS | 3380 | RSS: Main: 224485376 16:24:50 INFO - PROCESS | 3380 | 16:27:53 INFO - PROCESS | 3380 | Cycle 1(1): loaded http://localhost:45349/tests/dromaeo/cssquery-yui.html (next: http://localhost:45349/tests/dromaeo/cssquery-dojo.html) 16:27:54 INFO - PROCESS | 3380 | RSS: Main: 285937664 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | __start_tp_report 16:27:54 INFO - PROCESS | 3380 | _x_x_mozilla_page_load 16:27:54 INFO - PROCESS | 3380 | _x_x_mozilla_page_load_details 16:27:54 INFO - PROCESS | 3380 | |i|pagename|runs| 16:27:54 INFO - PROCESS | 3380 | |0;dojo.html;2621.6460777794996;2622.4492856500133;2657.986710066449;2664.347234927443;2666.6666666666656;4512.142692888349;4573.679842411033;4639.930401043983;4673.369095172151;4686.953130468694;4514.90970180597;4552.362669226311;4554.134714404261;4602.171609110359;4603.608693261077;4194.475690538683;4197.622214000739;4207.537170911197;4240.406343111967;4304.332828411586;4288.313869780835;4355.5644435556505;4374.562543745632;4382.298832186851;4397.736135831844;4321.416608757809;4354.956450435487;4363.716358436692;4376.868693939187;4403.801828917706;4177.8537751178865;4187.539370669224;4220.9155816883795;4223.66210703146;4226.492820861486;4174.540800511942;4198.097409057067;4237.936430953539;4241.151769646083;4270.252705776507;4607.562281583244;4652.8138874444985;4659.161350956826;4660.790264438108;4662.417197850269;4746.5490778376;4908.509149085098;4929.186684197103;4981.900361992776;5042;5034.3455350804525;5037.143685573461;5042.268871013683;5046.495350464961;5063.645544811828;4584.862454126382;4719.197736384823;4733.100710864925;4738.455077666113;4738.47876733559;4964.081644895634;5024.874378140576;5026.874328141753;5071.909539448962;5096.082705113041;4873.951260487421;4888.193448081098;4888.975555122272;4889.364382630307;4891.3396691446915;4970.2793095001025;5010.874728131753;5028.497150284942;5031.77357018935;5035.269885866529;4705.411823522059;4892.217245240744;4929.852104436801;4968.6025117990475;4974.452810190876;4929.827456039022;4932.506749325111;4941.827036053793;4945.950540494621;4982;4092.1201941582704;4167.5832416758685;4186.476690413699;4187.392828039978;4239.1521695660995;3618.4210526315665;3650.8904732858055;3672.8163591820303;3716.4053751399647;3723.1809002019513;4302.9569704303185;4304.182205380967;4306.978465107717;4335.241332766753;4345.130973805251;4957.05815894986;4960.950390496121;4963.329950456714;4972.4033116025585;5016.673916195436;4179.310413781692;4247.108107297502;4281.400603915455;4315.395844581761;4350.912981740411;4426.3581780641625;4428.047969686469;4431.069475410135;4464.241079016575;4473.686841921034;4671.813127474863;4677.134730074947;4678.345031695633;4678.578927896506;4726.952730472789;4751.548602882721;4898.681585696918;4917.58200552957;4923.901521969612;4939.234418665113;4662.0442809224705;4673.135469938073;4674.719516829001;4679.181143299977;4703.6942598730975;4877.6341774367775;4887.291342755209;4888.780004899859;4891.144049791344;4936.9259461107395;4841.467438581823;4901.313816065683;4911.950880491149;4923.89212427201;4928.8767780805765;4790.233562629963;4791.209450440639;4811.037792441456;4812.711237325771;4863;4705.952940470552;4809.759512024455;4859.586935110555;4861.4895435980125;4910.140725372974;4713.505081966344;4760.047990401864;4762.904741905073;4770.713757174581;4822.541858523435;4833.685810422311;4866.391701037371;4873.95126048735;4876.463589005278;4929.2359684248995;4662.487126416024;4727.739974301447;4739.099571081621;4753.500882407436;4754.429468463668 16:27:54 INFO - PROCESS | 3380 | |1;ext.html;555.0702573189906;562.9605927585071;572.530524969525;583.6527266276564;587.6415386614166;26365.604515932253;38824;40491.79754101229;40565.59434405655;43764;40747.59252407475;40955.59044409555;41072.794636026854;41079.383809242885;41124;7831.334336581412;7961.442699011071;7976.720814771485;7978.60106994648;7981.361491080728;7839.843203135933;7871.606419678993;7901.8024549386155;7926.445148839583;7929.801754956115;7875.4487185897015;7903.881441778378;7919.762407127767;7921.20787921209;7926.960365198166;7909.762707118795;7913.841723165505;7950.602469876483;7953.045634523836;7954.125046244909;7826.491278066907;7878.724244651467;7886.2113788621255;7887.842243155163;7890.763277101696;39582.60417395818;39855.20289594192;39913.80043099795;39928.401073983914;39964.800175998935;2892.5516544935567;2901.0716570697386;3008.4133593949186;3105.7825952183357;3205.423023855705;29231.90392982835;29325.853370733224;29994.700052999407;30269;31194.376112477625;3498.1079824644553;3500;3512.7365447591533;3525.171584677624;3553.9111522211892;264657;265287;265403;265616;266023;32018.839905800323;33316.3336733264;51727.22409163866;52183.73908130435;52197.73901130546;34002;34042.31915361728;34095;34125;34130.82934585311;12391;12452.439640216213;12479.313637749829;12492.188007779465;12494.875051249554;9359.672411465703;9433.103855133751;9447.102525260088;9451.432914025181;9468;7930.1276859545405;7949.165337639464;7958.602069896482;7999.1600881907725;8034.799130021796;3178.3007738298;3178.4278829810855;3190.7128358447853;3199.9040028799172;3205.794205794206;857.8069934264865;919.9632014719471;959.1032384720309;966.1353587575906;977.1645243317004;23853.522929541312;24679.87660061724;24749.25752227436;25191.496170076494;25525.61711574291;821.7740121466644;870.2254993056185;959.947202903833;961.1205746741792;1030.7165529479453;14214;14430.855691443161;14484.34820433083;14541.636459088395;15036;384349;389208;389369;390534;391626;49213.753931230116;49254.75372623114;49303.50696493061;49820.50179498231;49838;1036.5646428500047;1127.137739629168;1131.1572878205789;1160.2400427719856;1164.8776878427811;27551.31121721973;28787.27276363425;29319;29417.264568385104;29547.704522955355;41509.37735934024;41565.79217103895;42052.57947420487;42163;42167;303142;303932;305151;306015;306507;22146.66779998269;22817.65773513432;24412;25358.746412536377;25433;42016.369754453095;42019.36970945377;42047;42848;42925.14149716926;35311;35431.291374172884;35825.82087089548;36275.45586816147;36684.26631467303;37028.259434810614;37310;37656.81171594124;37690.811545943194;37770.43344349892;35322.470162947066;35992.100197495274;36017.459738103425;36032.81983590065;36473.635263647026;31885;32802.37108713115;33131.337373252885;33157.66842331642;33548.161295967795;24798;25041.74958250467;25281.241562753148;25341.859616316942;25735.356616084748 16:27:54 INFO - PROCESS | 3380 | |2;jquery.html;330.12352204896007;357.4209780156149;360.72224387221837;364.087959661049;385.4911516797826;13130.803037954427;16369.263383147758;16616.25226864791;17813.465596032107;18812.623747525042;17497.300107995696;17876.463706088805;18111.275548978025;18127.637447251047;18153.273869045257;4917.139500587401;5174.353205849269;5214.8696282592855;5217.321748172732;5221.164613661815;3702.4816525686424;5206.817761378353;5865.912011319833;6181.412765787243;6190.752369905198;6131.632102073868;6136.386361363872;6147.323794382614;6147.938520614804;6149.877002459948;5323.866903327428;5630.324361076677;5631.324241091095;5681.7727290908315;5695.174199741055;5318.946810531884;5459.426760190163;5506;5507.807226747044;5508.20131080995;12949;13005.414756335987;13201;13203.933980330134;14637.41450341985;1969.4190213886795;1973.1219607274768;2025.8581899267058;2196.846220764547;2222.6110430674566;9517.666881659177;9526.714198574055;9770.413775173512;9787.119159275628;9965.352252103663;1723.8793284470091;1784.2595322940963;1812.3294381078954;1861.8231268029515;1863.6924907390264;70117;72513.63743181303;74002.25997740007;74007.62996185038;76421;8860.158284962918;9526;9695.127438530566;9840.852387214198;10264.281500294908;12945.611631651065;13850.584482465541;13863.861361386458;14246.572602821932;14356.210408427427;6846.212685541127;7025.121859767529;7079.1858936223;7102.68037938294;7171.175314838756;6546.672666366662;6551.6069035858;6626.83432914181;6704.094947182166;6728.932710672929;4510.819567217341;4528.388667529841;4529.547045295444;4542.545745425431;4573.108243892409;1803.603207294393;1834.9633007340046;1847.2333981397837;1867.9533011674816;1871.111222169486;815.339574944297;844.3034496540256;874.7944233105216;884.9911500885038;900.9684661036832;16926.492205233862;17460.476185714448;17536.386226482013;17616.55958601045;17736;832.3353293413173;841.3984001439001;852.1052894460915;875.7810547363159;896.950667713269;9293.72118836436;9730.513474326255;10131.189504839595;10552.788944221073;10563.313384629975;285942;289104;289392;390272;401068;72869.63565182246;74504;74969.25030749732;79719.20280797234;80163;909.7680091576606;917.5870858113875;923.3121324613206;948.5873644964463;948.9430634161972;15898.284577193823;16606.335746570003;16653.25060372262;16770.16149192511;17016.914915425343;37438.2512349746;37984.430233545965;38998.610013899495;39090.21819563536;39336.213275734895;52534.21198682099;53531.19703204377;53965.730171350464;54906.72546637241;55594.44405556055;6411.551191416556;6965.512414131165;7088.964555177397;7136.0723105995885;7275.818104547428;9864.260180486348;10337.121344685786;10420.635277765241;10469.947650262005;10478.842817357594;6998.300169982961;7004;7158.355747982707;7317.524360916524;9249.12133347331;4002.1995600880523;4843.733594652328;7145.642717863982;7402.148752893486;7410.178520657827;7371;7604.962257698371;7612.923870761221;7669.69321227167;7724.575148366894;9759.804803904024;10056.446895420822;10060.144887684335;10209.540570674486;10304.433256170683;7108.786736397917;7198.424126070006;7413.285421969314;7724.806879828049;9868.457234852152 16:27:54 INFO - PROCESS | 3380 | |3;mootools.html;1190.4404929683046;1331.920084794912;1398.7202559488103;1421.5024741340526;1694.8135705072448;7017.438604911607;7373.741919032828;7386.0767404074495;7573.545587264754;7583;7385.667644955976;7524.586147761885;7538.924610753891;7540.095188577378;7567.583782891938;2171.174953517661;2173.4783651923544;2189.3650841256017;2190.8356873234493;2195.1877805212093;2173.4240426287047;2194.901229444679;2221.0893533651206;2221.3669104305277;2225.4436390902274;2115.3971118231307;2116.2275769344155;2139.4330502416874;2145.6888751131078;2146.5170336674278;2133.4026472587702;2148.5917675641736;2150.9247176348754;2151.634222182233;2153.8599991000615;2071.078370125295;2111.461577297779;2117.4494631395796;2124.904379302935;2125.883076430796;7160.427165826722;7196;7209.855802883913;7264.418846492268;7580.204078571725;4340.52254252032;4464.977675111636;4465.754383508905;4478.664100192498;4480.417545719069;2728.8089833711647;2791.9302017449522;2838.914832555027;2846.3168839478585;2849.472847523194;845.1970627903517;848.9363297752632;857.3269983063266;1714.9828501714949;1784.161444121261;9718.319717619841;9731.805363892683;9776.902230977741;9780.315377923549;9783.951080244624;3706.5366829146355;3791.1991803893484;3940.665043471337;3942.1327307992196;3954.0905591714068;7859.371250299962;7911.090224624127;7915.287624113857;7916.683332666629;7920.5247685139075;4383.298672212431;4396.868093957187;4397.142557201315;4397.428334316518;4403.757793321334;3593.568771747407;3928.3910993796007;4112.177564487055;4112.670986321087;4127.112670775798;3283.2612662150823;3310.8841190558214;3388.8813891514174;3410.9488357674654;3411.232472693624;967.8596603492595;1002.1431675917132;1007.9042490963346;1011.1910471622673;1011.231464087299;776.8718161503401;789.0452552411532;793.1275596843426;797.7885860247039;800.0679208721774;4858.271259311132;5009.242418986296;5101.183810590362;5225.503577160163;5233.058049551041;391.3230111906418;399.8020979615051;401.1855932457117;405.67748639831404;421.7279854493834;3426.674465925733;3436.261203841186;3439.2949445363643;3452.533907922398;3469.011331770433;8865.423747456394;8959.552022398853;9054.728358149267;9188.632454701738;9192.21866141372;9033.954830225806;9126.13301736334;9167.587458564381;9186.586603602855;9197.264218862474;6651.102101216274;6949.722011119498;7019.8596028079155;7073.186583542855;7105.218425973138;5216.139337009351;5238.921416178684;5328.428113706388;5451.863703407446;5489.945100548944;8568.186022328118;8577.442466239674;8671.739847804576;8712.259457946146;8712.520811355183;8054;8390.454620449651;8410.747677569683;8642.783930401538;8730.995935467503;5805;5985.940140598538;5990.760369585168;6531.836704082436;6689.832754181184;6614.305497922649;6705.832354191184;6880.174379074343;6928.203256625553;7144.749933752294;6383.96808015957;6509.121268628674;6599.0761293418;6605.372489613479;6626.801195964129;6022.337542870193;6219.782307619212;6241.500679945684;6401.7119229633845;6482.416582507597;5873.089671100986;5906.616069955439;5912.083627037816;5916.704164791657;5924.081767326072;4207.431996680409;4234.87295381139;4322.157179349996;4387.056782791715;4550.908981820411;4201.222773786859;4291.463567054118;4336.219480493542;4374.103308821747;4407.867763967086 16:27:54 INFO - PROCESS | 3380 | |4;prototype.html;285.8785016368043;290.4843902073819;300.1505738759311;300.74887468963414;301.84455005672066;4908.852734417968;4949.430815456223;4954.554090131896;4955.984023275233;4965.35450391449;4595.977020114903;4714.434267887849;4734.100520901026;4747.62019038477;4873.366462359888;3711.7030637548933;3712.851485940559;3719.274741425423;3790.9431358529646;3838.309104361214;3767.2653832502674;3818.8281527331337;3827.4116241759634;3837.270918525471;3847.942280865789;3591.8204089795536;3767.472553842464;3806.5051543299332;3835.520559930009;3839.6544311012003;3773.52830896138;3776.848926042955;3810.3522401191863;3811.2377524494937;3820.1977584707106;3262.951055734166;3267.2648654052887;3299.3566254580364;3301.1912081539936;3307.8676852925855;4760.404949381327;4767.523247675239;4773.236282194832;4784.378030856001;4795.400574928134;4075.5109386873764;4307.181635489247;4316.719413238129;4336.154449882274;4354.978225108885;5041.647084704072;5061.316722242487;5070.645054846163;5082.60228437179;5118.744062796845;3611.494390785292;3628.76413033152;3640.290143422034;3641.326354624403;3649.416093425066;5179.533841954242;5303.920441193385;5313.096773548545;5386.2997810284805;5408.783648654049;3625.860742162137;3650.634936506381;3658.213484100931;3674.3386190485435;3687.428448590419;3722.1439069014277;3736.7384283099923;3737.2151848111657;3737.8691745788765;3742.700583953277;4299.78501074945;4303.010307629263;4311.741295522279;4315.6763242756915;4317.697761156752;4926.433460152057;5131.538161565402;5148.819791307286;5151.304573882553;5157.149070403417;1228.582282024116;1264.9051321150766;1286.6075846866795;1286.6397408725572;1286.897048236139;838.2455789789238;868.4355169139983;871.6905498547924;874.7681864305964;877.8727084572702;4529.6376289896725;4532.116237333688;4542.364069030338;4549.613282870993;4561.224591819332;5226.137687281632;5229.50319719626;5245.737713114329;5252.29094072303;5262.421133675292;4376.059147283349;4472.351509031238;4486.170058539181;4504.887377815581;4551.749653769075;2707.7563019328354;2745.23133522614;2762.7927905407173;2765.778737700979;2779.902703405371;4921.704697718148;4930.7288099154175;4931.238506436407;4948.604111671058;4970.254461830682;5220.486059042911;5247.4490178530705;5359.919601205985;5360.437154098841;5373.301470808769;4935.506449355107;4951.950480495221;4957.925631115609;4967;4975.427825800007;5232.058229518571;5236.633435659467;5246.711430871339;5252.868678283074;5272.288241087557;4931.926021109614;5212.843614691566;5224.138017227115;5234.476552344735;5234.534330387498;4997.750112494434;5030.346055012971;5041.269015992734;5061.4938506150565;5062;3674.6141655125825;3728.3848165052464;3730.1793605406765;3744.8876533704033;3747.7938713371027;5133.768980395966;5270.973645131749;5292.814751483679;5293.285406470077;5320.973395133;5093.668911520739;5139.152039913372;5140.897182056413;5141.820036298861;5206.349206349207;5043.117454445531;5179.430262671028;5192.5586325162785;5205.6616319939085;5207.114790485551;4806.927896081632;4900.436449808318;5076.492350764894;5076.974615126901;5103.87240318995;5176.197689358156;5186.351706036746;5190.299309593157;5202.843914682566;5241.711705856214;5075.746212689277;5080.974595127149;5081.383087709906;5087.23691446286;5100.107481190703 16:27:54 INFO - PROCESS | 3380 | |5;yui.html;589.6786251492937;653.8169312592473;675.6115233740594;725.7822653204039;739.0540108660913;11079.833802492969;11659;11760.941195294012;11786.057115430767;11788.17482776206;11860.347680877549;12124.333161676104;12271.938640306786;12275.44760485778;12298.077644176677;778.9727359542431;793.5357815677806;794.5471081483556;794.618583080119;795.4272923495074;627.7081157261872;764.8393837294175;771.0939645916054;797.9521228726265;803.0644299391203;843.1821133500525;843.7131375332385;844.5270648436864;844.5945945945949;844.7634662294568;656.5666660004374;833.1293797981124;833.6582001379436;833.7165363776345;833.954132522711;592.9288485381738;608.1546650156287;609.7164818359485;610.4414460768436;610.8075956073823;12054.397280135958;12071.93964030183;12075.275483470932;12089.395530223454;12189.207701499374;12437.689057773538;13109.541166059238;13214.86785132146;13258.86741132586;13378.732425351536;11482.368469734161;11592.362420066893;11735.23720958135;11790.764184716343;12244.265344079384;4080.10237747695;4108.630223279919;4201.4538110045805;4291.635211007068;4294.763787991659;61957.69021154865;62230.37769622291;62387;62465.687671561805;62520.37479625191;10268.075873171301;10313.226508011929;10342.069213770797;10498.317609355367;10740.462976851126;18947.242110315434;18972.715409268872;18980.335688250845;18986.905065474584;18988.240470381308;6631.801045968628;6672.0325552794575;6717.294684058202;6741.426978706865;6899.206591242071;3673.210259794157;3677.742558020913;3693.298273328059;3717.9442308365396;3750.6905630514975;2637.4856902904135;2713.308106432847;2713.71505991872;2715.2804506805714;2715.660542432196;483.97156043407756;493.2084005171719;498.80546586831167;505.7167985927892;508.6871573982048;456.728246693217;470.6258524473052;503.2024241577068;522.3888050980286;576.9989068965342;7210.823224002775;7228.494005419675;7297.416206703451;7680.961595191989;7689.50018248812;504.70979186966906;524.8530411484791;532.6378062917254;550.8707150341784;565.8189379398635;3276.754243431752;3332.683395077464;3361.3613413451367;3442.019024577983;3505.404081306133;53853.73073134609;55355.44644553503;79148.20851791408;81350.59324703338;82235;46524.767376162905;81992;82366;83040.58479707563;84034;71605.64197178981;73165.26834731798;73976;74199;74817.6259118701;8428.24145826854;8467.661293548435;8490.660373584988;8558;8603.95698021531;35575;43581.564184357754;44441.777791110835;44680.32979505245;44836.77581612071;61905.69047154736;63621.68189159025;63749.362506374346;64600;66250;9734.318597698377;9770.169535589555;9925.553350099408;11217.158713096387;11455.427228638702;12358.567450139202;12934.935325323313;13007.219566826021;13804.198939057942;13804.93097534506;11317.547298107984;11932;12520.373981301082;12885.549005784753;13272.601821945356;12406.193597416139;12522.248665080124;12553.748925021266;12573.371331433575;12689.492420303084;11487.185580126687;11696.824547631622;12090.214136081127;12347.444365003417;12507.624771256877;10235.846462302923;10324.535395907053;10369.118624916966;10512.526936288039;10727.249092563447;10027.348222365523;10050.8492372613;10059.647912323033;10081.747956301151;10219.540120694437 16:27:54 INFO - PROCESS | 3380 | __end_tp_report 16:27:54 INFO - PROCESS | 3380 | __start_cc_report 16:27:54 INFO - PROCESS | 3380 | _x_x_mozilla_cycle_collect,1004 16:27:54 INFO - PROCESS | 3380 | __end_cc_report 16:27:54 INFO - PROCESS | 3380 | __startTimestamp1468366074020__endTimestamp 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | ------- Summary: start ------- 16:27:54 INFO - PROCESS | 3380 | Number of tests: 6 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | [#0] dojo.html Cycles:165 Average:4582.89 Median:4705.95 stddev:470.38 (10.0%) stddev-sans-first:445.96 16:27:54 INFO - PROCESS | 3380 | Values: 2621.6 2622.4 2658.0 2664.3 2666.7 4512.1 4573.7 4639.9 4673.4 4687.0 4514.9 4552.4 4554.1 4602.2 4603.6 4194.5 4197.6 4207.5 4240.4 4304.3 4288.3 4355.6 4374.6 4382.3 4397.7 4321.4 4355.0 4363.7 4376.9 4403.8 4177.9 4187.5 4220.9 4223.7 4226.5 4174.5 4198.1 4237.9 4241.2 4270.3 4607.6 4652.8 4659.2 4660.8 4662.4 4746.5 4908.5 4929.2 4981.9 5042.0 5034.3 5037.1 5042.3 5046.5 5063.6 4584.9 4719.2 4733.1 4738.5 4738.5 4964.1 5024.9 5026.9 5071.9 5096.1 4874.0 4888.2 4889.0 4889.4 4891.3 4970.3 5010.9 5028.5 5031.8 5035.3 4705.4 4892.2 4929.9 4968.6 4974.5 4929.8 4932.5 4941.8 4946.0 4982.0 4092.1 4167.6 4186.5 4187.4 4239.2 3618.4 3650.9 3672.8 3716.4 3723.2 4303.0 4304.2 4307.0 4335.2 4345.1 4957.1 4961.0 4963.3 4972.4 5016.7 4179.3 4247.1 4281.4 4315.4 4350.9 4426.4 4428.0 4431.1 4464.2 4473.7 4671.8 4677.1 4678.3 4678.6 4727.0 4751.5 4898.7 4917.6 4923.9 4939.2 4662.0 4673.1 4674.7 4679.2 4703.7 4877.6 4887.3 4888.8 4891.1 4936.9 4841.5 4901.3 4912.0 4923.9 4928.9 4790.2 4791.2 4811.0 4812.7 4863.0 4706.0 4809.8 4859.6 4861.5 4910.1 4713.5 4760.0 4762.9 4770.7 4822.5 4833.7 4866.4 4874.0 4876.5 4929.2 4662.5 4727.7 4739.1 4753.5 4754.4 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | [#1] ext.html Cycles:180 Average:46190.54 Median:37483.41 stddev:85455.53 (228.0%) stddev-sans-first:85626.56 16:27:54 INFO - PROCESS | 3380 | Values: 555.1 563.0 572.5 583.7 587.6 26365.6 38824.0 40491.8 40565.6 43764.0 40747.6 40955.6 41072.8 41079.4 41124.0 7831.3 7961.4 7976.7 7978.6 7981.4 7839.8 7871.6 7901.8 7926.4 7929.8 7875.4 7903.9 7919.8 7921.2 7927.0 7909.8 7913.8 7950.6 7953.0 7954.1 7826.5 7878.7 7886.2 7887.8 7890.8 39582.6 39855.2 39913.8 39928.4 39964.8 2892.6 2901.1 3008.4 3105.8 3205.4 29231.9 29325.9 29994.7 30269.0 31194.4 3498.1 3500.0 3512.7 3525.2 3553.9 264657.0 265287.0 265403.0 265616.0 266023.0 32018.8 33316.3 51727.2 52183.7 52197.7 34002.0 34042.3 34095.0 34125.0 34130.8 12391.0 12452.4 12479.3 12492.2 12494.9 9359.7 9433.1 9447.1 9451.4 9468.0 7930.1 7949.2 7958.6 7999.2 8034.8 3178.3 3178.4 3190.7 3199.9 3205.8 857.8 920.0 959.1 966.1 977.2 23853.5 24679.9 24749.3 25191.5 25525.6 821.8 870.2 959.9 961.1 1030.7 14214.0 14430.9 14484.3 14541.6 15036.0 384349.0 389208.0 389369.0 390534.0 391626.0 49213.8 49254.8 49303.5 49820.5 49838.0 1036.6 1127.1 1131.2 1160.2 1164.9 27551.3 28787.3 29319.0 29417.3 29547.7 41509.4 41565.8 42052.6 42163.0 42167.0 303142.0 303932.0 305151.0 306015.0 306507.0 22146.7 22817.7 24412.0 25358.7 25433.0 42016.4 42019.4 42047.0 42848.0 42925.1 35311.0 35431.3 35825.8 36275.5 36684.3 37028.3 37310.0 37656.8 37690.8 37770.4 35322.5 35992.1 36017.5 36032.8 36473.6 31885.0 32802.4 33131.3 33157.7 33548.2 24798.0 25041.7 25281.2 25341.9 25735.4 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | [#2] jquery.html Cycles:180 Average:22504.36 Median:29712.58 stddev:56053.19 (188.7%) stddev-sans-first:56185.71 16:27:54 INFO - PROCESS | 3380 | Values: 330.1 357.4 360.7 364.1 385.5 13130.8 16369.3 16616.3 17813.5 18812.6 17497.3 17876.5 18111.3 18127.6 18153.3 4917.1 5174.4 5214.9 5217.3 5221.2 3702.5 5206.8 5865.9 6181.4 6190.8 6131.6 6136.4 6147.3 6147.9 6149.9 5323.9 5630.3 5631.3 5681.8 5695.2 5318.9 5459.4 5506.0 5507.8 5508.2 12949.0 13005.4 13201.0 13203.9 14637.4 1969.4 1973.1 2025.9 2196.8 2222.6 9517.7 9526.7 9770.4 9787.1 9965.4 1723.9 1784.3 1812.3 1861.8 1863.7 70117.0 72513.6 74002.3 74007.6 76421.0 8860.2 9526.0 9695.1 9840.9 10264.3 12945.6 13850.6 13863.9 14246.6 14356.2 6846.2 7025.1 7079.2 7102.7 7171.2 6546.7 6551.6 6626.8 6704.1 6728.9 4510.8 4528.4 4529.5 4542.5 4573.1 1803.6 1835.0 1847.2 1868.0 1871.1 815.3 844.3 874.8 885.0 901.0 16926.5 17460.5 17536.4 17616.6 17736.0 832.3 841.4 852.1 875.8 897.0 9293.7 9730.5 10131.2 10552.8 10563.3 285942.0 289104.0 289392.0 390272.0 401068.0 72869.6 74504.0 74969.3 79719.2 80163.0 909.8 917.6 923.3 948.6 948.9 15898.3 16606.3 16653.3 16770.2 17016.9 37438.3 37984.4 38998.6 39090.2 39336.2 52534.2 53531.2 53965.7 54906.7 55594.4 6411.6 6965.5 7089.0 7136.1 7275.8 9864.3 10337.1 10420.6 10469.9 10478.8 6998.3 7004.0 7158.4 7317.5 9249.1 4002.2 4843.7 7145.6 7402.1 7410.2 7371.0 7605.0 7612.9 7669.7 7724.6 9759.8 10056.4 10060.1 10209.5 10304.4 7108.8 7198.4 7413.3 7724.8 9868.5 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | [#3] mootools.html Cycles:180 Average:4857.23 Median:5229.28 stddev:2679.52 (51.2%) stddev-sans-first:2672.86 16:27:54 INFO - PROCESS | 3380 | Values: 1190.4 1331.9 1398.7 1421.5 1694.8 7017.4 7373.7 7386.1 7573.5 7583.0 7385.7 7524.6 7538.9 7540.1 7567.6 2171.2 2173.5 2189.4 2190.8 2195.2 2173.4 2194.9 2221.1 2221.4 2225.4 2115.4 2116.2 2139.4 2145.7 2146.5 2133.4 2148.6 2150.9 2151.6 2153.9 2071.1 2111.5 2117.4 2124.9 2125.9 7160.4 7196.0 7209.9 7264.4 7580.2 4340.5 4465.0 4465.8 4478.7 4480.4 2728.8 2791.9 2838.9 2846.3 2849.5 845.2 848.9 857.3 1715.0 1784.2 9718.3 9731.8 9776.9 9780.3 9784.0 3706.5 3791.2 3940.7 3942.1 3954.1 7859.4 7911.1 7915.3 7916.7 7920.5 4383.3 4396.9 4397.1 4397.4 4403.8 3593.6 3928.4 4112.2 4112.7 4127.1 3283.3 3310.9 3388.9 3410.9 3411.2 967.9 1002.1 1007.9 1011.2 1011.2 776.9 789.0 793.1 797.8 800.1 4858.3 5009.2 5101.2 5225.5 5233.1 391.3 399.8 401.2 405.7 421.7 3426.7 3436.3 3439.3 3452.5 3469.0 8865.4 8959.6 9054.7 9188.6 9192.2 9034.0 9126.1 9167.6 9186.6 9197.3 6651.1 6949.7 7019.9 7073.2 7105.2 5216.1 5238.9 5328.4 5451.9 5489.9 8568.2 8577.4 8671.7 8712.3 8712.5 8054.0 8390.5 8410.7 8642.8 8731.0 5805.0 5985.9 5990.8 6531.8 6689.8 6614.3 6705.8 6880.2 6928.2 7144.7 6384.0 6509.1 6599.1 6605.4 6626.8 6022.3 6219.8 6241.5 6401.7 6482.4 5873.1 5906.6 5912.1 5916.7 5924.1 4207.4 4234.9 4322.2 4387.1 4550.9 4201.2 4291.5 4336.2 4374.1 4407.9 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | [#4] prototype.html Cycles:180 Average:4241.30 Median:4886.90 stddev:1244.13 (25.5%) stddev-sans-first:1211.68 16:27:54 INFO - PROCESS | 3380 | Values: 285.9 290.5 300.2 300.7 301.8 4908.9 4949.4 4954.6 4956.0 4965.4 4596.0 4714.4 4734.1 4747.6 4873.4 3711.7 3712.9 3719.3 3790.9 3838.3 3767.3 3818.8 3827.4 3837.3 3847.9 3591.8 3767.5 3806.5 3835.5 3839.7 3773.5 3776.8 3810.4 3811.2 3820.2 3263.0 3267.3 3299.4 3301.2 3307.9 4760.4 4767.5 4773.2 4784.4 4795.4 4075.5 4307.2 4316.7 4336.2 4355.0 5041.6 5061.3 5070.6 5082.6 5118.7 3611.5 3628.8 3640.3 3641.3 3649.4 5179.5 5303.9 5313.1 5386.3 5408.8 3625.9 3650.6 3658.2 3674.3 3687.4 3722.1 3736.7 3737.2 3737.9 3742.7 4299.8 4303.0 4311.7 4315.7 4317.7 4926.4 5131.5 5148.8 5151.3 5157.1 1228.6 1264.9 1286.6 1286.6 1286.9 838.2 868.4 871.7 874.8 877.9 4529.6 4532.1 4542.4 4549.6 4561.2 5226.1 5229.5 5245.7 5252.3 5262.4 4376.1 4472.4 4486.2 4504.9 4551.7 2707.8 2745.2 2762.8 2765.8 2779.9 4921.7 4930.7 4931.2 4948.6 4970.3 5220.5 5247.4 5359.9 5360.4 5373.3 4935.5 4952.0 4957.9 4967.0 4975.4 5232.1 5236.6 5246.7 5252.9 5272.3 4931.9 5212.8 5224.1 5234.5 5234.5 4997.8 5030.3 5041.3 5061.5 5062.0 3674.6 3728.4 3730.2 3744.9 3747.8 5133.8 5271.0 5292.8 5293.3 5321.0 5093.7 5139.2 5140.9 5141.8 5206.3 5043.1 5179.4 5192.6 5205.7 5207.1 4806.9 4900.4 5076.5 5077.0 5103.9 5176.2 5186.4 5190.3 5202.8 5241.7 5075.7 5081.0 5081.4 5087.2 5100.1 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - PROCESS | 3380 | [#5] yui.html Cycles:180 Average:16732.37 Median:44758.55 stddev:22780.52 (50.9%) stddev-sans-first:22812.18 16:27:54 INFO - PROCESS | 3380 | Values: 589.7 653.8 675.6 725.8 739.1 11079.8 11659.0 11760.9 11786.1 11788.2 11860.3 12124.3 12271.9 12275.4 12298.1 779.0 793.5 794.5 794.6 795.4 627.7 764.8 771.1 798.0 803.1 843.2 843.7 844.5 844.6 844.8 656.6 833.1 833.7 833.7 834.0 592.9 608.2 609.7 610.4 610.8 12054.4 12071.9 12075.3 12089.4 12189.2 12437.7 13109.5 13214.9 13258.9 13378.7 11482.4 11592.4 11735.2 11790.8 12244.3 4080.1 4108.6 4201.5 4291.6 4294.8 61957.7 62230.4 62387.0 62465.7 62520.4 10268.1 10313.2 10342.1 10498.3 10740.5 18947.2 18972.7 18980.3 18986.9 18988.2 6631.8 6672.0 6717.3 6741.4 6899.2 3673.2 3677.7 3693.3 3717.9 3750.7 2637.5 2713.3 2713.7 2715.3 2715.7 484.0 493.2 498.8 505.7 508.7 456.7 470.6 503.2 522.4 577.0 7210.8 7228.5 7297.4 7681.0 7689.5 504.7 524.9 532.6 550.9 565.8 3276.8 3332.7 3361.4 3442.0 3505.4 53853.7 55355.4 79148.2 81350.6 82235.0 46524.8 81992.0 82366.0 83040.6 84034.0 71605.6 73165.3 73976.0 74199.0 74817.6 8428.2 8467.7 8490.7 8558.0 8604.0 35575.0 43581.6 44441.8 44680.3 44836.8 61905.7 63621.7 63749.4 64600.0 66250.0 9734.3 9770.2 9925.6 11217.2 11455.4 12358.6 12934.9 13007.2 13804.2 13804.9 11317.5 11932.0 12520.4 12885.5 13272.6 12406.2 12522.2 12553.7 12573.4 12689.5 11487.2 11696.8 12090.2 12347.4 12507.6 10235.8 10324.5 10369.1 10512.5 10727.2 10027.3 10050.8 10059.6 10081.7 10219.5 16:27:54 INFO - PROCESS | 3380 | -------- Summary: end -------- 16:27:54 INFO - PROCESS | 3380 | 16:27:54 INFO - TEST-INFO | 3380: exit 0 16:27:54 INFO - TEST-OK | dromaeo_css | took 1122460ms 16:27:54 INFO - TEST-START | kraken 16:27:54 INFO - operating with platform_type : linux_ 16:27:54 INFO - Initialising browser for kraken test... 16:27:54 INFO - TEST-INFO | started process 3580 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU3RR9F/profile http://localhost:45349/getInfo.html) 16:27:59 INFO - PROCESS | 3580 | __metrics Screen width/height:1600/1200 16:27:59 INFO - PROCESS | 3580 | colorDepth:24 16:27:59 INFO - PROCESS | 3580 | Browser inner width/height: 1024/697 16:27:59 INFO - PROCESS | 3580 | __metrics 16:28:00 INFO - PROCESS | 3580 | 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" 16:28:00 INFO - PROCESS | 3580 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:28:01 INFO - PROCESS | 3580 | console.error: PushService: 16:28:01 INFO - PROCESS | 3580 | stateChangeProcessEnqueue: Error transitioning state 16:28:01 INFO - PROCESS | 3580 | UnknownError 16:28:01 INFO - TEST-INFO | 3580: exit 0 16:28:01 INFO - Browser initialized. 16:28:01 INFO - Running cycle 1/1 for kraken test... 16:28:01 INFO - TEST-INFO | started process 3664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpU3RR9F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:28:13 INFO - PROCESS | 3664 | RSS: Main: 159219712 16:28:13 INFO - PROCESS | 3664 | 16:28:16 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45349/tests/kraken/driver.html?testName=audio-beat-detection) 16:28:17 INFO - PROCESS | 3664 | RSS: Main: 217481216 16:28:17 INFO - PROCESS | 3664 | 16:28:20 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45349/tests/kraken/driver.html?testName=audio-dft) 16:28:20 INFO - PROCESS | 3664 | RSS: Main: 203575296 16:28:20 INFO - PROCESS | 3664 | 16:28:23 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45349/tests/kraken/driver.html?testName=audio-fft) 16:28:24 INFO - PROCESS | 3664 | RSS: Main: 271056896 16:28:24 INFO - PROCESS | 3664 | 16:28:26 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45349/tests/kraken/driver.html?testName=audio-oscillator) 16:28:27 INFO - PROCESS | 3664 | RSS: Main: 181157888 16:28:27 INFO - PROCESS | 3664 | 16:28:29 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45349/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:28:30 INFO - PROCESS | 3664 | RSS: Main: 185536512 16:28:30 INFO - PROCESS | 3664 | 16:28:33 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45349/tests/kraken/driver.html?testName=imaging-darkroom) 16:28:34 INFO - PROCESS | 3664 | RSS: Main: 221687808 16:28:34 INFO - PROCESS | 3664 | 16:28:37 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45349/tests/kraken/driver.html?testName=imaging-desaturate) 16:28:38 INFO - PROCESS | 3664 | RSS: Main: 222908416 16:28:38 INFO - PROCESS | 3664 | 16:28:41 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45349/tests/kraken/driver.html?testName=json-parse-financial) 16:28:41 INFO - PROCESS | 3664 | RSS: Main: 223072256 16:28:41 INFO - PROCESS | 3664 | 16:28:43 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45349/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:28:44 INFO - PROCESS | 3664 | RSS: Main: 209436672 16:28:45 INFO - PROCESS | 3664 | 16:28:45 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:28:46 INFO - PROCESS | 3664 | RSS: Main: 213766144 16:28:46 INFO - PROCESS | 3664 | 16:28:48 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:28:49 INFO - PROCESS | 3664 | RSS: Main: 214622208 16:28:49 INFO - PROCESS | 3664 | 16:28:52 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:28:52 INFO - PROCESS | 3664 | RSS: Main: 226721792 16:28:52 INFO - PROCESS | 3664 | 16:28:55 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:28:56 INFO - PROCESS | 3664 | RSS: Main: 226811904 16:28:56 INFO - PROCESS | 3664 | 16:28:57 INFO - PROCESS | 3664 | Cycle 1(1): loaded http://localhost:45349/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45349/tests/kraken/driver.html?testName=ai-astar) 16:28:58 INFO - PROCESS | 3664 | RSS: Main: 268189696 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | __start_tp_report 16:28:58 INFO - PROCESS | 3664 | _x_x_mozilla_page_load 16:28:58 INFO - PROCESS | 3664 | _x_x_mozilla_page_load_details 16:28:58 INFO - PROCESS | 3664 | |i|pagename|runs| 16:28:58 INFO - PROCESS | 3664 | |0;ai-astar;104;116;114;113;114;114;114;113;113;113 16:28:58 INFO - PROCESS | 3664 | |1;audio-beat-detection;159;122;125;172;127;122;149;125;162;122 16:28:58 INFO - PROCESS | 3664 | |2;audio-dft;162;162;161;159;163;163;161;162;161;162 16:28:58 INFO - PROCESS | 3664 | |3;audio-fft;74;75;79;129;78;77;75;76;102;75 16:28:58 INFO - PROCESS | 3664 | |4;audio-oscillator;84;91;114;85;87;85;125;85;85;130 16:28:58 INFO - PROCESS | 3664 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;116 16:28:58 INFO - PROCESS | 3664 | |6;imaging-darkroom;146;146;146;146;146;146;146;146;145;145 16:28:58 INFO - PROCESS | 3664 | |7;imaging-desaturate;95;95;95;95;95;95;94;95;95;95 16:28:58 INFO - PROCESS | 3664 | |8;json-parse-financial;63;63;61;62;63;64;62;63;62;62 16:28:58 INFO - PROCESS | 3664 | |9;json-stringify-tinderbox;65;66;65;67;67;65;65;71;64;65 16:28:58 INFO - PROCESS | 3664 | |10;stanford-crypto-aes;79;79;78;77;77;77;83;78;78;84 16:28:58 INFO - PROCESS | 3664 | |11;stanford-crypto-ccm;130;126;125;125;136;136;137;136;167;134 16:28:58 INFO - PROCESS | 3664 | |12;stanford-crypto-pbkdf2;164;165;166;168;171;165;169;166;162;162 16:28:58 INFO - PROCESS | 3664 | |13;stanford-crypto-sha256-iterative;70;71;66;66;70;67;70;66;69;66 16:28:58 INFO - PROCESS | 3664 | __end_tp_report 16:28:58 INFO - PROCESS | 3664 | __start_cc_report 16:28:58 INFO - PROCESS | 3664 | _x_x_mozilla_cycle_collect,1914 16:28:58 INFO - PROCESS | 3664 | __end_cc_report 16:28:58 INFO - PROCESS | 3664 | __startTimestamp1468366138240__endTimestamp 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | ------- Summary: start ------- 16:28:58 INFO - PROCESS | 3664 | Number of tests: 14 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.22 (2.8%) stddev-sans-first:0.97 16:28:58 INFO - PROCESS | 3664 | Values: 104.0 116.0 114.0 113.0 114.0 114.0 114.0 113.0 113.0 113.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:138.00 stddev:19.77 (14.3%) stddev-sans-first:19.53 16:28:58 INFO - PROCESS | 3664 | Values: 159.0 122.0 125.0 172.0 127.0 122.0 149.0 125.0 162.0 122.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#2] audio-dft Cycles:10 Average:161.60 Median:162.00 stddev:1.17 (0.7%) stddev-sans-first:1.24 16:28:58 INFO - PROCESS | 3664 | Values: 162.0 162.0 161.0 159.0 163.0 163.0 161.0 162.0 161.0 162.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#3] audio-fft Cycles:10 Average:84.00 Median:75.50 stddev:17.85 (23.6%) stddev-sans-first:18.56 16:28:58 INFO - PROCESS | 3664 | Values: 74.0 75.0 79.0 129.0 78.0 77.0 75.0 76.0 102.0 75.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:85.00 stddev:18.39 (21.6%) stddev-sans-first:18.88 16:28:58 INFO - PROCESS | 3664 | Values: 84.0 91.0 114.0 85.0 87.0 85.0 125.0 85.0 85.0 130.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:28:58 INFO - PROCESS | 3664 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 16:28:58 INFO - PROCESS | 3664 | Values: 146.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 145.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:28:58 INFO - PROCESS | 3664 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 16:28:58 INFO - PROCESS | 3664 | Values: 63.0 63.0 61.0 62.0 63.0 64.0 62.0 63.0 62.0 62.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#9] json-stringify-tinderbox Cycles:10 Average:66.00 Median:65.50 stddev:2.00 (3.1%) stddev-sans-first:2.09 16:28:58 INFO - PROCESS | 3664 | Values: 65.0 66.0 65.0 67.0 67.0 65.0 65.0 71.0 64.0 65.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#10] stanford-crypto-aes Cycles:10 Average:79.00 Median:78.50 stddev:2.49 (3.2%) stddev-sans-first:2.65 16:28:58 INFO - PROCESS | 3664 | Values: 79.0 79.0 78.0 77.0 77.0 77.0 83.0 78.0 78.0 84.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#11] stanford-crypto-ccm Cycles:10 Average:135.20 Median:136.00 stddev:12.19 (9.0%) stddev-sans-first:12.78 16:28:58 INFO - PROCESS | 3664 | Values: 130.0 126.0 125.0 125.0 136.0 136.0 137.0 136.0 167.0 134.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.80 Median:166.00 stddev:2.90 (1.7%) stddev-sans-first:3.00 16:28:58 INFO - PROCESS | 3664 | Values: 164.0 165.0 166.0 168.0 171.0 165.0 169.0 166.0 162.0 162.0 16:28:58 INFO - PROCESS | 3664 | 16:28:58 INFO - PROCESS | 3664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:69.50 stddev:2.08 (3.0%) stddev-sans-first:2.09 16:28:58 INFO - PROCESS | 3664 | Values: 70.0 71.0 66.0 66.0 70.0 67.0 70.0 66.0 69.0 66.0 16:28:58 INFO - PROCESS | 3664 | -------- Summary: end -------- 16:28:58 INFO - PROCESS | 3664 | 16:28:59 INFO - TEST-INFO | 3664: exit 0 16:28:59 INFO - TEST-OK | kraken | took 64483ms 16:28:59 INFO - SUITE-END | took 1186s 16:28:59 INFO - Completed test suite (00:19:47) 16:28:59 INFO - javascript benchmark 16:28:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2621.6460777794996, 2622.4492856500133, 2657.986710066449, 2664.347234927443, 2666.6666666666656, 4512.142692888349, 4573.679842411033, 4639.930401043983, 4673.369095172151, 4686.953130468694, 4514.90970180597, 4552.362669226311, 4554.134714404261, 4602.171609110359, 4603.608693261077, 4194.475690538683, 4197.622214000739, 4207.537170911197, 4240.406343111967, 4304.332828411586, 4288.313869780835, 4355.5644435556505, 4374.562543745632, 4382.298832186851, 4397.736135831844, 4321.416608757809, 4354.956450435487, 4363.716358436692, 4376.868693939187, 4403.801828917706, 4177.8537751178865, 4187.539370669224, 4220.9155816883795, 4223.66210703146, 4226.492820861486, 4174.540800511942, 4198.097409057067, 4237.936430953539, 4241.151769646083, 4270.252705776507, 4607.562281583244, 4652.8138874444985, 4659.161350956826, 4660.790264438108, 4662.417197850269, 4746.5490778376, 4908.509149085098, 4929.186684197103, 4981.900361992776, 5042.0, 5034.3455350804525, 5037.143685573461, 5042.268871013683, 5046.495350464961, 5063.645544811828, 4584.862454126382, 4719.197736384823, 4733.100710864925, 4738.455077666113, 4738.47876733559, 4964.081644895634, 5024.874378140576, 5026.874328141753, 5071.909539448962, 5096.082705113041, 4873.951260487421, 4888.193448081098, 4888.975555122272, 4889.364382630307, 4891.3396691446915, 4970.2793095001025, 5010.874728131753, 5028.497150284942, 5031.77357018935, 5035.269885866529, 4705.411823522059, 4892.217245240744, 4929.852104436801, 4968.6025117990475, 4974.452810190876, 4929.827456039022, 4932.506749325111, 4941.827036053793, 4945.950540494621, 4982.0, 4092.1201941582704, 4167.5832416758685, 4186.476690413699, 4187.392828039978, 4239.1521695660995, 3618.4210526315665, 3650.8904732858055, 3672.8163591820303, 3716.4053751399647, 3723.1809002019513, 4302.9569704303185, 4304.182205380967, 4306.978465107717, 4335.241332766753, 4345.130973805251, 4957.05815894986, 4960.950390496121, 4963.329950456714, 4972.4033116025585, 5016.673916195436, 4179.310413781692, 4247.108107297502, 4281.400603915455, 4315.395844581761, 4350.912981740411, 4426.3581780641625, 4428.047969686469, 4431.069475410135, 4464.241079016575, 4473.686841921034, 4671.813127474863, 4677.134730074947, 4678.345031695633, 4678.578927896506, 4726.952730472789, 4751.548602882721, 4898.681585696918, 4917.58200552957, 4923.901521969612, 4939.234418665113, 4662.0442809224705, 4673.135469938073, 4674.719516829001, 4679.181143299977, 4703.6942598730975, 4877.6341774367775, 4887.291342755209, 4888.780004899859, 4891.144049791344, 4936.9259461107395, 4841.467438581823, 4901.313816065683, 4911.950880491149, 4923.89212427201, 4928.8767780805765, 4790.233562629963, 4791.209450440639, 4811.037792441456, 4812.711237325771, 4863.0, 4705.952940470552, 4809.759512024455, 4859.586935110555, 4861.4895435980125, 4910.140725372974, 4713.505081966344, 4760.047990401864, 4762.904741905073, 4770.713757174581, 4822.541858523435, 4833.685810422311, 4866.391701037371, 4873.95126048735, 4876.463589005278, 4929.2359684248995, 4662.487126416024, 4727.739974301447, 4739.099571081621, 4753.500882407436, 4754.429468463668], "value": 4553.744475740745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [555.0702573189906, 562.9605927585071, 572.530524969525, 583.6527266276564, 587.6415386614166, 26365.604515932253, 38824.0, 40491.79754101229, 40565.59434405655, 43764.0, 40747.59252407475, 40955.59044409555, 41072.794636026854, 41079.383809242885, 41124.0, 7831.334336581412, 7961.442699011071, 7976.720814771485, 7978.60106994648, 7981.361491080728, 7839.843203135933, 7871.606419678993, 7901.8024549386155, 7926.445148839583, 7929.801754956115, 7875.4487185897015, 7903.881441778378, 7919.762407127767, 7921.20787921209, 7926.960365198166, 7909.762707118795, 7913.841723165505, 7950.602469876483, 7953.045634523836, 7954.125046244909, 7826.491278066907, 7878.724244651467, 7886.2113788621255, 7887.842243155163, 7890.763277101696, 39582.60417395818, 39855.20289594192, 39913.80043099795, 39928.401073983914, 39964.800175998935, 2892.5516544935567, 2901.0716570697386, 3008.4133593949186, 3105.7825952183357, 3205.423023855705, 29231.90392982835, 29325.853370733224, 29994.700052999407, 30269.0, 31194.376112477625, 3498.1079824644553, 3500.0, 3512.7365447591533, 3525.171584677624, 3553.9111522211892, 264657.0, 265287.0, 265403.0, 265616.0, 266023.0, 32018.839905800323, 33316.3336733264, 51727.22409163866, 52183.73908130435, 52197.73901130546, 34002.0, 34042.31915361728, 34095.0, 34125.0, 34130.82934585311, 12391.0, 12452.439640216213, 12479.313637749829, 12492.188007779465, 12494.875051249554, 9359.672411465703, 9433.103855133751, 9447.102525260088, 9451.432914025181, 9468.0, 7930.1276859545405, 7949.165337639464, 7958.602069896482, 7999.1600881907725, 8034.799130021796, 3178.3007738298, 3178.4278829810855, 3190.7128358447853, 3199.9040028799172, 3205.794205794206, 857.8069934264865, 919.9632014719471, 959.1032384720309, 966.1353587575906, 977.1645243317004, 23853.522929541312, 24679.87660061724, 24749.25752227436, 25191.496170076494, 25525.61711574291, 821.7740121466644, 870.2254993056185, 959.947202903833, 961.1205746741792, 1030.7165529479453, 14214.0, 14430.855691443161, 14484.34820433083, 14541.636459088395, 15036.0, 384349.0, 389208.0, 389369.0, 390534.0, 391626.0, 49213.753931230116, 49254.75372623114, 49303.50696493061, 49820.50179498231, 49838.0, 1036.5646428500047, 1127.137739629168, 1131.1572878205789, 1160.2400427719856, 1164.8776878427811, 27551.31121721973, 28787.27276363425, 29319.0, 29417.264568385104, 29547.704522955355, 41509.37735934024, 41565.79217103895, 42052.57947420487, 42163.0, 42167.0, 303142.0, 303932.0, 305151.0, 306015.0, 306507.0, 22146.66779998269, 22817.65773513432, 24412.0, 25358.746412536377, 25433.0, 42016.369754453095, 42019.36970945377, 42047.0, 42848.0, 42925.14149716926, 35311.0, 35431.291374172884, 35825.82087089548, 36275.45586816147, 36684.26631467303, 37028.259434810614, 37310.0, 37656.81171594124, 37690.811545943194, 37770.43344349892, 35322.470162947066, 35992.100197495274, 36017.459738103425, 36032.81983590065, 36473.635263647026, 31885.0, 32802.37108713115, 33131.337373252885, 33157.66842331642, 33548.161295967795, 24798.0, 25041.74958250467, 25281.241562753148, 25341.859616316942, 25735.356616084748], "value": 16299.03014564513, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.12352204896007, 357.4209780156149, 360.72224387221837, 364.087959661049, 385.4911516797826, 13130.803037954427, 16369.263383147758, 16616.25226864791, 17813.465596032107, 18812.623747525042, 17497.300107995696, 17876.463706088805, 18111.275548978025, 18127.637447251047, 18153.273869045257, 4917.139500587401, 5174.353205849269, 5214.8696282592855, 5217.321748172732, 5221.164613661815, 3702.4816525686424, 5206.817761378353, 5865.912011319833, 6181.412765787243, 6190.752369905198, 6131.632102073868, 6136.386361363872, 6147.323794382614, 6147.938520614804, 6149.877002459948, 5323.866903327428, 5630.324361076677, 5631.324241091095, 5681.7727290908315, 5695.174199741055, 5318.946810531884, 5459.426760190163, 5506.0, 5507.807226747044, 5508.20131080995, 12949.0, 13005.414756335987, 13201.0, 13203.933980330134, 14637.41450341985, 1969.4190213886795, 1973.1219607274768, 2025.8581899267058, 2196.846220764547, 2222.6110430674566, 9517.666881659177, 9526.714198574055, 9770.413775173512, 9787.119159275628, 9965.352252103663, 1723.8793284470091, 1784.2595322940963, 1812.3294381078954, 1861.8231268029515, 1863.6924907390264, 70117.0, 72513.63743181303, 74002.25997740007, 74007.62996185038, 76421.0, 8860.158284962918, 9526.0, 9695.127438530566, 9840.852387214198, 10264.281500294908, 12945.611631651065, 13850.584482465541, 13863.861361386458, 14246.572602821932, 14356.210408427427, 6846.212685541127, 7025.121859767529, 7079.1858936223, 7102.68037938294, 7171.175314838756, 6546.672666366662, 6551.6069035858, 6626.83432914181, 6704.094947182166, 6728.932710672929, 4510.819567217341, 4528.388667529841, 4529.547045295444, 4542.545745425431, 4573.108243892409, 1803.603207294393, 1834.9633007340046, 1847.2333981397837, 1867.9533011674816, 1871.111222169486, 815.339574944297, 844.3034496540256, 874.7944233105216, 884.9911500885038, 900.9684661036832, 16926.492205233862, 17460.476185714448, 17536.386226482013, 17616.55958601045, 17736.0, 832.3353293413173, 841.3984001439001, 852.1052894460915, 875.7810547363159, 896.950667713269, 9293.72118836436, 9730.513474326255, 10131.189504839595, 10552.788944221073, 10563.313384629975, 285942.0, 289104.0, 289392.0, 390272.0, 401068.0, 72869.63565182246, 74504.0, 74969.25030749732, 79719.20280797234, 80163.0, 909.7680091576606, 917.5870858113875, 923.3121324613206, 948.5873644964463, 948.9430634161972, 15898.284577193823, 16606.335746570003, 16653.25060372262, 16770.16149192511, 17016.914915425343, 37438.2512349746, 37984.430233545965, 38998.610013899495, 39090.21819563536, 39336.213275734895, 52534.21198682099, 53531.19703204377, 53965.730171350464, 54906.72546637241, 55594.44405556055, 6411.551191416556, 6965.512414131165, 7088.964555177397, 7136.0723105995885, 7275.818104547428, 9864.260180486348, 10337.121344685786, 10420.635277765241, 10469.947650262005, 10478.842817357594, 6998.300169982961, 7004.0, 7158.355747982707, 7317.524360916524, 9249.12133347331, 4002.1995600880523, 4843.733594652328, 7145.642717863982, 7402.148752893486, 7410.178520657827, 7371.0, 7604.962257698371, 7612.923870761221, 7669.69321227167, 7724.575148366894, 9759.804803904024, 10056.446895420822, 10060.144887684335, 10209.540570674486, 10304.433256170683, 7108.786736397917, 7198.424126070006, 7413.285421969314, 7724.806879828049, 9868.457234852152], "value": 7920.016802698097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1190.4404929683046, 1331.920084794912, 1398.7202559488103, 1421.5024741340526, 1694.8135705072448, 7017.438604911607, 7373.741919032828, 7386.0767404074495, 7573.545587264754, 7583.0, 7385.667644955976, 7524.586147761885, 7538.924610753891, 7540.095188577378, 7567.583782891938, 2171.174953517661, 2173.4783651923544, 2189.3650841256017, 2190.8356873234493, 2195.1877805212093, 2173.4240426287047, 2194.901229444679, 2221.0893533651206, 2221.3669104305277, 2225.4436390902274, 2115.3971118231307, 2116.2275769344155, 2139.4330502416874, 2145.6888751131078, 2146.5170336674278, 2133.4026472587702, 2148.5917675641736, 2150.9247176348754, 2151.634222182233, 2153.8599991000615, 2071.078370125295, 2111.461577297779, 2117.4494631395796, 2124.904379302935, 2125.883076430796, 7160.427165826722, 7196.0, 7209.855802883913, 7264.418846492268, 7580.204078571725, 4340.52254252032, 4464.977675111636, 4465.754383508905, 4478.664100192498, 4480.417545719069, 2728.8089833711647, 2791.9302017449522, 2838.914832555027, 2846.3168839478585, 2849.472847523194, 845.1970627903517, 848.9363297752632, 857.3269983063266, 1714.9828501714949, 1784.161444121261, 9718.319717619841, 9731.805363892683, 9776.902230977741, 9780.315377923549, 9783.951080244624, 3706.5366829146355, 3791.1991803893484, 3940.665043471337, 3942.1327307992196, 3954.0905591714068, 7859.371250299962, 7911.090224624127, 7915.287624113857, 7916.683332666629, 7920.5247685139075, 4383.298672212431, 4396.868093957187, 4397.142557201315, 4397.428334316518, 4403.757793321334, 3593.568771747407, 3928.3910993796007, 4112.177564487055, 4112.670986321087, 4127.112670775798, 3283.2612662150823, 3310.8841190558214, 3388.8813891514174, 3410.9488357674654, 3411.232472693624, 967.8596603492595, 1002.1431675917132, 1007.9042490963346, 1011.1910471622673, 1011.231464087299, 776.8718161503401, 789.0452552411532, 793.1275596843426, 797.7885860247039, 800.0679208721774, 4858.271259311132, 5009.242418986296, 5101.183810590362, 5225.503577160163, 5233.058049551041, 391.3230111906418, 399.8020979615051, 401.1855932457117, 405.67748639831404, 421.7279854493834, 3426.674465925733, 3436.261203841186, 3439.2949445363643, 3452.533907922398, 3469.011331770433, 8865.423747456394, 8959.552022398853, 9054.728358149267, 9188.632454701738, 9192.21866141372, 9033.954830225806, 9126.13301736334, 9167.587458564381, 9186.586603602855, 9197.264218862474, 6651.102101216274, 6949.722011119498, 7019.8596028079155, 7073.186583542855, 7105.218425973138, 5216.139337009351, 5238.921416178684, 5328.428113706388, 5451.863703407446, 5489.945100548944, 8568.186022328118, 8577.442466239674, 8671.739847804576, 8712.259457946146, 8712.520811355183, 8054.0, 8390.454620449651, 8410.747677569683, 8642.783930401538, 8730.995935467503, 5805.0, 5985.940140598538, 5990.760369585168, 6531.836704082436, 6689.832754181184, 6614.305497922649, 6705.832354191184, 6880.174379074343, 6928.203256625553, 7144.749933752294, 6383.96808015957, 6509.121268628674, 6599.0761293418, 6605.372489613479, 6626.801195964129, 6022.337542870193, 6219.782307619212, 6241.500679945684, 6401.7119229633845, 6482.416582507597, 5873.089671100986, 5906.616069955439, 5912.083627037816, 5916.704164791657, 5924.081767326072, 4207.431996680409, 4234.87295381139, 4322.157179349996, 4387.056782791715, 4550.908981820411, 4201.222773786859, 4291.463567054118, 4336.219480493542, 4374.103308821747, 4407.867763967086], "value": 3889.1200633249628, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.8785016368043, 290.4843902073819, 300.1505738759311, 300.74887468963414, 301.84455005672066, 4908.852734417968, 4949.430815456223, 4954.554090131896, 4955.984023275233, 4965.35450391449, 4595.977020114903, 4714.434267887849, 4734.100520901026, 4747.62019038477, 4873.366462359888, 3711.7030637548933, 3712.851485940559, 3719.274741425423, 3790.9431358529646, 3838.309104361214, 3767.2653832502674, 3818.8281527331337, 3827.4116241759634, 3837.270918525471, 3847.942280865789, 3591.8204089795536, 3767.472553842464, 3806.5051543299332, 3835.520559930009, 3839.6544311012003, 3773.52830896138, 3776.848926042955, 3810.3522401191863, 3811.2377524494937, 3820.1977584707106, 3262.951055734166, 3267.2648654052887, 3299.3566254580364, 3301.1912081539936, 3307.8676852925855, 4760.404949381327, 4767.523247675239, 4773.236282194832, 4784.378030856001, 4795.400574928134, 4075.5109386873764, 4307.181635489247, 4316.719413238129, 4336.154449882274, 4354.978225108885, 5041.647084704072, 5061.316722242487, 5070.645054846163, 5082.60228437179, 5118.744062796845, 3611.494390785292, 3628.76413033152, 3640.290143422034, 3641.326354624403, 3649.416093425066, 5179.533841954242, 5303.920441193385, 5313.096773548545, 5386.2997810284805, 5408.783648654049, 3625.860742162137, 3650.634936506381, 3658.213484100931, 3674.3386190485435, 3687.428448590419, 3722.1439069014277, 3736.7384283099923, 3737.2151848111657, 3737.8691745788765, 3742.700583953277, 4299.78501074945, 4303.010307629263, 4311.741295522279, 4315.6763242756915, 4317.697761156752, 4926.433460152057, 5131.538161565402, 5148.819791307286, 5151.304573882553, 5157.149070403417, 1228.582282024116, 1264.9051321150766, 1286.6075846866795, 1286.6397408725572, 1286.897048236139, 838.2455789789238, 868.4355169139983, 871.6905498547924, 874.7681864305964, 877.8727084572702, 4529.6376289896725, 4532.116237333688, 4542.364069030338, 4549.613282870993, 4561.224591819332, 5226.137687281632, 5229.50319719626, 5245.737713114329, 5252.29094072303, 5262.421133675292, 4376.059147283349, 4472.351509031238, 4486.170058539181, 4504.887377815581, 4551.749653769075, 2707.7563019328354, 2745.23133522614, 2762.7927905407173, 2765.778737700979, 2779.902703405371, 4921.704697718148, 4930.7288099154175, 4931.238506436407, 4948.604111671058, 4970.254461830682, 5220.486059042911, 5247.4490178530705, 5359.919601205985, 5360.437154098841, 5373.301470808769, 4935.506449355107, 4951.950480495221, 4957.925631115609, 4967.0, 4975.427825800007, 5232.058229518571, 5236.633435659467, 5246.711430871339, 5252.868678283074, 5272.288241087557, 4931.926021109614, 5212.843614691566, 5224.138017227115, 5234.476552344735, 5234.534330387498, 4997.750112494434, 5030.346055012971, 5041.269015992734, 5061.4938506150565, 5062.0, 3674.6141655125825, 3728.3848165052464, 3730.1793605406765, 3744.8876533704033, 3747.7938713371027, 5133.768980395966, 5270.973645131749, 5292.814751483679, 5293.285406470077, 5320.973395133, 5093.668911520739, 5139.152039913372, 5140.897182056413, 5141.820036298861, 5206.349206349207, 5043.117454445531, 5179.430262671028, 5192.5586325162785, 5205.6616319939085, 5207.114790485551, 4806.927896081632, 4900.436449808318, 5076.492350764894, 5076.974615126901, 5103.87240318995, 5176.197689358156, 5186.351706036746, 5190.299309593157, 5202.843914682566, 5241.711705856214, 5075.746212689277, 5080.974595127149, 5081.383087709906, 5087.23691446286, 5100.107481190703], "value": 3842.9410076278896, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [589.6786251492937, 653.8169312592473, 675.6115233740594, 725.7822653204039, 739.0540108660913, 11079.833802492969, 11659.0, 11760.941195294012, 11786.057115430767, 11788.17482776206, 11860.347680877549, 12124.333161676104, 12271.938640306786, 12275.44760485778, 12298.077644176677, 778.9727359542431, 793.5357815677806, 794.5471081483556, 794.618583080119, 795.4272923495074, 627.7081157261872, 764.8393837294175, 771.0939645916054, 797.9521228726265, 803.0644299391203, 843.1821133500525, 843.7131375332385, 844.5270648436864, 844.5945945945949, 844.7634662294568, 656.5666660004374, 833.1293797981124, 833.6582001379436, 833.7165363776345, 833.954132522711, 592.9288485381738, 608.1546650156287, 609.7164818359485, 610.4414460768436, 610.8075956073823, 12054.397280135958, 12071.93964030183, 12075.275483470932, 12089.395530223454, 12189.207701499374, 12437.689057773538, 13109.541166059238, 13214.86785132146, 13258.86741132586, 13378.732425351536, 11482.368469734161, 11592.362420066893, 11735.23720958135, 11790.764184716343, 12244.265344079384, 4080.10237747695, 4108.630223279919, 4201.4538110045805, 4291.635211007068, 4294.763787991659, 61957.69021154865, 62230.37769622291, 62387.0, 62465.687671561805, 62520.37479625191, 10268.075873171301, 10313.226508011929, 10342.069213770797, 10498.317609355367, 10740.462976851126, 18947.242110315434, 18972.715409268872, 18980.335688250845, 18986.905065474584, 18988.240470381308, 6631.801045968628, 6672.0325552794575, 6717.294684058202, 6741.426978706865, 6899.206591242071, 3673.210259794157, 3677.742558020913, 3693.298273328059, 3717.9442308365396, 3750.6905630514975, 2637.4856902904135, 2713.308106432847, 2713.71505991872, 2715.2804506805714, 2715.660542432196, 483.97156043407756, 493.2084005171719, 498.80546586831167, 505.7167985927892, 508.6871573982048, 456.728246693217, 470.6258524473052, 503.2024241577068, 522.3888050980286, 576.9989068965342, 7210.823224002775, 7228.494005419675, 7297.416206703451, 7680.961595191989, 7689.50018248812, 504.70979186966906, 524.8530411484791, 532.6378062917254, 550.8707150341784, 565.8189379398635, 3276.754243431752, 3332.683395077464, 3361.3613413451367, 3442.019024577983, 3505.404081306133, 53853.73073134609, 55355.44644553503, 79148.20851791408, 81350.59324703338, 82235.0, 46524.767376162905, 81992.0, 82366.0, 83040.58479707563, 84034.0, 71605.64197178981, 73165.26834731798, 73976.0, 74199.0, 74817.6259118701, 8428.24145826854, 8467.661293548435, 8490.660373584988, 8558.0, 8603.95698021531, 35575.0, 43581.564184357754, 44441.777791110835, 44680.32979505245, 44836.77581612071, 61905.69047154736, 63621.68189159025, 63749.362506374346, 64600.0, 66250.0, 9734.318597698377, 9770.169535589555, 9925.553350099408, 11217.158713096387, 11455.427228638702, 12358.567450139202, 12934.935325323313, 13007.219566826021, 13804.198939057942, 13804.93097534506, 11317.547298107984, 11932.0, 12520.373981301082, 12885.549005784753, 13272.601821945356, 12406.193597416139, 12522.248665080124, 12553.748925021266, 12573.371331433575, 12689.492420303084, 11487.185580126687, 11696.824547631622, 12090.214136081127, 12347.444365003417, 12507.624771256877, 10235.846462302923, 10324.535395907053, 10369.118624916966, 10512.526936288039, 10727.249092563447, 10027.348222365523, 10050.8492372613, 10059.647912323033, 10081.747956301151, 10219.540120694437], "value": 6433.574112669073, "unit": "score"}], "name": "dromaeo_css", "value": 6195.058095333874, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 114.0, 113.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 122.0, 125.0, 172.0, 127.0, 122.0, 149.0, 125.0, 162.0, 122.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 162.0, 161.0, 159.0, 163.0, 163.0, 161.0, 162.0, 161.0, 162.0], "value": 161.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 79.0, 129.0, 78.0, 77.0, 75.0, 76.0, 102.0, 75.0], "value": 84.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 114.0, 85.0, 87.0, 85.0, 125.0, 85.0, 85.0, 130.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 61.0, 62.0, 63.0, 64.0, 62.0, 63.0, 62.0, 62.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 65.0, 67.0, 67.0, 65.0, 65.0, 71.0, 64.0, 65.0], "value": 66.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 78.0, 77.0, 77.0, 77.0, 83.0, 78.0, 78.0, 84.0], "value": 79.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 126.0, 125.0, 125.0, 136.0, 136.0, 137.0, 136.0, 167.0, 134.0], "value": 135.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 166.0, 168.0, 171.0, 165.0, 169.0, 166.0, 162.0, 162.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 66.0, 66.0, 70.0, 67.0, 70.0, 66.0, 69.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1528.1999999999998, "alertThreshold": 2.0}]} 16:28:59 INFO - Return code: 0 16:29:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:29:00 INFO - # TBPL SUCCESS # 16:29:00 INFO - Running post-action listener: _resource_record_post_action 16:29:00 INFO - ##### 16:29:00 INFO - ##### Finished run-tests step (success) 16:29:00 INFO - ##### 16:29:00 INFO - Running post-run listener: _resource_record_post_run 16:29:00 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 7892992; Write bytes: 431087616; Read time: 2744; Write time: 626892 16:29:00 INFO - TinderboxPrint: CPU usage
13.0% 16:29:00 INFO - TinderboxPrint: I/O read bytes / time
7,892,992 / 2,744 16:29:00 INFO - TinderboxPrint: I/O write bytes / time
431,087,616 / 626,892 16:29:00 INFO - TinderboxPrint: CPU idle
8,367.8 (87.0%) 16:29:00 INFO - TinderboxPrint: CPU user
1,200.3 (12.5%) 16:29:00 INFO - TinderboxPrint: Swap in / out
0 / 0 16:29:00 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 13393920; Read time: 0; Write time: 6116 16:29:00 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 6156288; Write bytes: 394600448; Read time: 2652; Write time: 593788 16:29:00 INFO - Running post-run listener: _upload_blobber_files 16:29:00 INFO - Blob upload gear active. 16:29:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:29:00 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 16:29:00 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'] 16:29:00 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 16:29:01 INFO - (blobuploader) - INFO - Open directory for files ... 16:29:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:29:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:29:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:05 INFO - (blobuploader) - INFO - Done attempting. 16:29:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:29:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:06 INFO - (blobuploader) - INFO - Done attempting. 16:29:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:29:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:29:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:29:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:29:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:29:06 INFO - (blobuploader) - INFO - Done attempting. 16:29:06 INFO - (blobuploader) - INFO - Iteration through files over. 16:29:06 INFO - Return code: 0 16:29:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:29:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:29:06 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/269ca9ae4b5993985d5ba39b602ac603b85afdbf7d678700ac41d1296cbf170bb520f19ec51f2690952cfdacc2e4b95b1b310b1b4286c4482cfbd68e68a46b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/706aabe12002fe5066e883e64a0280f9f2749089bddd3a671698a16c101a591f54dd4034df4d3abce37863847c3b2d2d1cdc3f8b4da3002d67b85444210f4413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c915a648684888ddcb2f09fee5f33730016e14b794f5a95166db0a82e10c7a096c565e5bd3f683762daa3a9d968e636cc6d83822fb4236b355bc93bfa2aa1da"} 16:29:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:29:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:29:06 INFO - Contents: 16:29:06 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/269ca9ae4b5993985d5ba39b602ac603b85afdbf7d678700ac41d1296cbf170bb520f19ec51f2690952cfdacc2e4b95b1b310b1b4286c4482cfbd68e68a46b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/706aabe12002fe5066e883e64a0280f9f2749089bddd3a671698a16c101a591f54dd4034df4d3abce37863847c3b2d2d1cdc3f8b4da3002d67b85444210f4413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c915a648684888ddcb2f09fee5f33730016e14b794f5a95166db0a82e10c7a096c565e5bd3f683762daa3a9d968e636cc6d83822fb4236b355bc93bfa2aa1da"} 16:29:06 INFO - Running post-run listener: copy_logs_to_upload_dir 16:29:06 INFO - Copying logs to upload dir... 16:29:06 INFO - mkdir: /builds/slave/test/build/upload/logs 16:29:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1247.525947 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 47 secs) (at 2016-07-12 16:29:06.854950) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 16:29:06.862691) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673 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/269ca9ae4b5993985d5ba39b602ac603b85afdbf7d678700ac41d1296cbf170bb520f19ec51f2690952cfdacc2e4b95b1b310b1b4286c4482cfbd68e68a46b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/706aabe12002fe5066e883e64a0280f9f2749089bddd3a671698a16c101a591f54dd4034df4d3abce37863847c3b2d2d1cdc3f8b4da3002d67b85444210f4413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c915a648684888ddcb2f09fee5f33730016e14b794f5a95166db0a82e10c7a096c565e5bd3f683762daa3a9d968e636cc6d83822fb4236b355bc93bfa2aa1da"} build_url:https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011193 build_url: 'https://queue.taskcluster.net/v1/task/DvdM9SxWQPWrnxE38w1ljQ/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/269ca9ae4b5993985d5ba39b602ac603b85afdbf7d678700ac41d1296cbf170bb520f19ec51f2690952cfdacc2e4b95b1b310b1b4286c4482cfbd68e68a46b70", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/706aabe12002fe5066e883e64a0280f9f2749089bddd3a671698a16c101a591f54dd4034df4d3abce37863847c3b2d2d1cdc3f8b4da3002d67b85444210f4413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/0c915a648684888ddcb2f09fee5f33730016e14b794f5a95166db0a82e10c7a096c565e5bd3f683762daa3a9d968e636cc6d83822fb4236b355bc93bfa2aa1da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-12 16:29:06.891096) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:29:06.891481) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=edae0fb5fee9fb758e8af6d3000002a4-1468361309.822464-1272469673 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004579 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-12 16:29:06.973255) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 16:29:06.973637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-12 16:29:06.974195) ========= ========= Total master_lag: 0.24 =========