builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-053 starttime: 1468019766.56 results: success (0) buildid: 20160708145625 builduid: ad25074bc14c4d3db4cd5de075d55ec6 revision: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.560968) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.561474) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.561787) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.576492) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.576796) ========= 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=920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-08 16:16:06-- 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% 27.1M=0s 2016-07-08 16:16:06 (27.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083412 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.673766) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.674054) ========= 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=920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020398 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.726016) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.726331) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa --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 fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa --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=920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-08 16:16:06,827 truncating revision to first 12 chars 2016-07-08 16:16:06,828 Setting DEBUG logging. 2016-07-08 16:16:06,828 attempt 1/10 2016-07-08 16:16:06,828 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fd8ff97bc294?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 16:16:07,168 unpacking tar archive at: mozilla-central-fd8ff97bc294/testing/mozharness/ program finished with exit code 0 elapsedTime=0.654496 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.394428) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.394729) ========= script_repo_revision: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.395119) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.395381) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.403539) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-07-08 16:16:07.403804) ========= /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=920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:16:07 INFO - MultiFileLogger online at 20160708 16:16:07 in /builds/slave/test 16:16:07 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:16:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:07 INFO - {'append_to_log': False, 16:16:07 INFO - 'base_work_dir': '/builds/slave/test', 16:16:07 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:16:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:07 INFO - 'branch': 'Firefox-Non-PGO', 16:16:07 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:07 INFO - 'config_files': ('talos/linux_config.py',), 16:16:07 INFO - 'default_actions': ('clobber', 16:16:07 INFO - 'read-buildbot-config', 16:16:07 INFO - 'download-and-extract', 16:16:07 INFO - 'populate-webroot', 16:16:07 INFO - 'create-virtualenv', 16:16:07 INFO - 'install', 16:16:07 INFO - 'run-tests'), 16:16:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:07 INFO - 'download_minidump_stackwalk': True, 16:16:07 INFO - 'download_symbols': 'ondemand', 16:16:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:07 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:07 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:07 INFO - 'installer_path': 'installer.exe', 16:16:07 INFO - 'log_level': 'info', 16:16:07 INFO - 'log_name': 'talos', 16:16:07 INFO - 'log_to_console': True, 16:16:07 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:16:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:16:07 INFO - 'opt_config_files': (), 16:16:07 INFO - 'pip_index': False, 16:16:07 INFO - 'sps_profile': False, 16:16:07 INFO - 'sps_profile_interval': 0, 16:16:07 INFO - 'suite': 'dromaeojs', 16:16:07 INFO - 'system_bits': '32', 16:16:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:07 INFO - 'title': 'talos-linux64-ix-053', 16:16:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:07 INFO - 'use_talos_json': True, 16:16:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:07 INFO - 'work_dir': 'build'} 16:16:07 INFO - ##### 16:16:07 INFO - ##### Running clobber step. 16:16:07 INFO - ##### 16:16:07 INFO - Running pre-action listener: _resource_record_pre_action 16:16:07 INFO - Running main action method: clobber 16:16:07 INFO - rmtree: /builds/slave/test/build 16:16:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:08 INFO - Running post-action listener: _resource_record_post_action 16:16:08 INFO - ##### 16:16:08 INFO - ##### Finished clobber step (success) 16:16:08 INFO - ##### 16:16:08 INFO - ##### 16:16:08 INFO - ##### Running read-buildbot-config step. 16:16:08 INFO - ##### 16:16:08 INFO - Running pre-action listener: _resource_record_pre_action 16:16:08 INFO - Running main action method: read_buildbot_config 16:16:08 INFO - Using buildbot properties: 16:16:08 INFO - { 16:16:08 INFO - "project": "", 16:16:08 INFO - "product": "firefox", 16:16:08 INFO - "script_repo_revision": "production", 16:16:08 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:16:08 INFO - "repository": "", 16:16:08 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 16:16:08 INFO - "buildid": "20160708145625", 16:16:08 INFO - "pgo_build": "False", 16:16:08 INFO - "basedir": "/builds/slave/test", 16:16:08 INFO - "buildnumber": 643, 16:16:08 INFO - "slavename": "talos-linux64-ix-053", 16:16:08 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:16:08 INFO - "platform": "ubuntu64_hw", 16:16:08 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:16:08 INFO - "branch": "mozilla-central", 16:16:08 INFO - "repo_path": "mozilla-central", 16:16:08 INFO - "revision": "fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa", 16:16:08 INFO - "stage_platform": "linux64", 16:16:08 INFO - "builduid": "ad25074bc14c4d3db4cd5de075d55ec6", 16:16:08 INFO - "slavebuilddir": "test" 16:16:08 INFO - } 16:16:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:16:08 INFO - Running post-action listener: _resource_record_post_action 16:16:08 INFO - ##### 16:16:08 INFO - ##### Finished read-buildbot-config step (success) 16:16:08 INFO - ##### 16:16:08 INFO - ##### 16:16:08 INFO - ##### Running download-and-extract step. 16:16:08 INFO - ##### 16:16:08 INFO - Running pre-action listener: _resource_record_pre_action 16:16:08 INFO - Running main action method: download_and_extract 16:16:08 INFO - mkdir: /builds/slave/test/build/tests 16:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:08 INFO - https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:16:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:16:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:16:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:16:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:16:12 INFO - Downloaded 1361 bytes. 16:16:12 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:16:12 INFO - Using the following test package requirements: 16:16:12 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:16:12 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:16:12 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:16:12 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'jsshell-linux-x86_64.zip'], 16:16:12 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:16:12 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:16:12 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:16:12 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:16:12 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:16:12 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:12 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:16:12 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:16:12 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:16:12 INFO - https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:16:13 INFO - Downloaded 22735479 bytes. 16:16:13 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:16:13 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:16:15 INFO - Return code: 0 16:16:15 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:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:15 INFO - https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:16:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:16:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:16:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:16:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:16:56 WARNING - Socket error when accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip: The read operation timed out 16:16:56 INFO - retry: attempt #1 caught exception: The read operation timed out 16:16:56 INFO - retry: Failed, sleeping 30 seconds before retrying 16:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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 #2 16:17:27 INFO - Downloaded 22735479 bytes. 16:17:27 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:17:27 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:17:28 INFO - Return code: 0 16:17: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:17:28 INFO - https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:17:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:17:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:17:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:17:29 INFO - Downloaded 14403333 bytes. 16:17:29 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:17:29 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:17:29 INFO - Return code: 0 16:17:29 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:17:29 INFO - https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:17:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:17:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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:17:31 INFO - Downloaded 56647594 bytes. 16:17:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:31 INFO - mkdir: /builds/slave/test/properties 16:17:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:31 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:31 INFO - Contents: 16:17:31 INFO - build_url:https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:17:32 INFO - Running post-action listener: _resource_record_post_action 16:17:32 INFO - Running post-action listener: set_extra_try_arguments 16:17:32 INFO - ##### 16:17:32 INFO - ##### Finished download-and-extract step (success) 16:17:32 INFO - ##### 16:17:32 INFO - ##### 16:17:32 INFO - ##### Running populate-webroot step. 16:17:32 INFO - ##### 16:17:32 INFO - Running pre-action listener: _resource_record_pre_action 16:17:32 INFO - Running main action method: populate_webroot 16:17:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:32 INFO - '--tppagecycles', 16:17:32 INFO - '7'], 16:17:32 INFO - 'tests': ['tsvgm']}}, 16:17:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:17:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['damp', 'tps']}, 16:17:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'tests': ['damp', 'tps']}, 16:17:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['dromaeo_dom']}, 16:17:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:32 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['basic_compositor_video']}, 16:17:32 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:17:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['a11yr', 16:17:32 INFO - 'ts_paint', 16:17:32 INFO - 'tpaint', 16:17:32 INFO - 'sessionrestore', 16:17:32 INFO - 'sessionrestore_no_auto_restore', 16:17:32 INFO - 'tabpaint']}, 16:17:32 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:32 INFO - 'ts_paint', 16:17:32 INFO - 'tpaint', 16:17:32 INFO - 'sessionrestore', 16:17:32 INFO - 'sessionrestore_no_auto_restore', 16:17:32 INFO - 'tabpaint']}, 16:17:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:17:32 INFO - 'ts_paint', 16:17:32 INFO - 'tpaint', 16:17:32 INFO - 'sessionrestore', 16:17:32 INFO - 'sessionrestore_no_auto_restore', 16:17:32 INFO - 'tabpaint']}, 16:17:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:17:32 INFO - 'ts_paint', 16:17:32 INFO - 'tpaint', 16:17:32 INFO - 'sessionrestore', 16:17:32 INFO - 'sessionrestore_no_auto_restore', 16:17:32 INFO - 'tabpaint']}, 16:17:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['a11yr', 16:17:32 INFO - 'ts_paint', 16:17:32 INFO - 'tpaint', 16:17:32 INFO - 'sessionrestore', 16:17:32 INFO - 'sessionrestore_no_auto_restore', 16:17:32 INFO - 'tabpaint']}, 16:17:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['a11yr', 16:17:32 INFO - 'ts_paint', 16:17:32 INFO - 'tpaint', 16:17:32 INFO - 'sessionrestore', 16:17:32 INFO - 'sessionrestore_no_auto_restore', 16:17:32 INFO - 'tabpaint']}, 16:17:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['tsvgx', 16:17:32 INFO - 'tsvgr_opacity', 16:17:32 INFO - 'tart', 16:17:32 INFO - 'tscrollx', 16:17:32 INFO - 'cart']}, 16:17:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:32 INFO - 'tsvgr_opacity', 16:17:32 INFO - 'tart', 16:17:32 INFO - 'tscrollx', 16:17:32 INFO - 'cart']}, 16:17:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'talos_options': ['--disable-e10s'], 16:17:32 INFO - 'tests': ['tp5o']}, 16:17:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'tests': ['tp5o']}, 16:17:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'talos_options': ['--disable-e10s', 16:17:32 INFO - '--xperf_path', 16:17:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:32 INFO - 'tests': ['tp5n']}, 16:17:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:32 INFO - 'talos_options': ['--xperf_path', 16:17:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:32 INFO - 'tests': ['tp5n']}}, 16:17:32 INFO - 'talos.zip': {'path': '', 16:17:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:32 INFO - Running post-action listener: _resource_record_post_action 16:17:32 INFO - ##### 16:17:32 INFO - ##### Finished populate-webroot step (success) 16:17:32 INFO - ##### 16:17:32 INFO - ##### 16:17:32 INFO - ##### Running create-virtualenv step. 16:17:32 INFO - ##### 16:17:32 INFO - Running pre-action listener: _resource_record_pre_action 16:17:32 INFO - Running main action method: create_virtualenv 16:17:32 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:32 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:17:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:32 INFO - Using real prefix '/usr' 16:17:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:32 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:34 INFO - Installing pip.................done. 16:17:34 INFO - Return code: 0 16:17:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17: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:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17: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', 'pip>=1.5'] in /builds/slave/test/build 16:17: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 pip>=1.5 16:17:34 INFO - Using env: {'DISPLAY': ':0', 16:17:34 INFO - 'HOME': '/home/cltbld', 16:17:34 INFO - 'LANG': 'en_US.UTF-8', 16:17:34 INFO - 'LANGUAGE': 'en_US:en', 16:17:34 INFO - 'LOGNAME': 'cltbld', 16:17:34 INFO - 'MAIL': '/var/mail/cltbld', 16:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:34 INFO - 'MOZ_NO_REMOTE': '1', 16:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:34 INFO - 'NO_EM_RESTART': '1', 16:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:34 INFO - 'PWD': '/builds/slave/test', 16:17:34 INFO - 'SHELL': '/bin/bash', 16:17:34 INFO - 'SHLVL': '1', 16:17:34 INFO - 'TERM': 'linux', 16:17:34 INFO - 'TMOUT': '86400', 16:17:34 INFO - 'USER': 'cltbld', 16:17:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:34 INFO - '_': '/tools/buildbot/bin/python'} 16:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17: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:17:34 INFO - Cleaning up... 16:17:34 INFO - Return code: 0 16:17:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:17: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:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17: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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17: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:17: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:17:34 INFO - Using env: {'DISPLAY': ':0', 16:17:34 INFO - 'HOME': '/home/cltbld', 16:17:34 INFO - 'LANG': 'en_US.UTF-8', 16:17:34 INFO - 'LANGUAGE': 'en_US:en', 16:17:34 INFO - 'LOGNAME': 'cltbld', 16:17:34 INFO - 'MAIL': '/var/mail/cltbld', 16:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:34 INFO - 'MOZ_NO_REMOTE': '1', 16:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:34 INFO - 'NO_EM_RESTART': '1', 16:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:34 INFO - 'PWD': '/builds/slave/test', 16:17:34 INFO - 'SHELL': '/bin/bash', 16:17:34 INFO - 'SHLVL': '1', 16:17:34 INFO - 'TERM': 'linux', 16:17:34 INFO - 'TMOUT': '86400', 16:17:34 INFO - 'USER': 'cltbld', 16:17:34 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:34 INFO - '_': '/tools/buildbot/bin/python'} 16:17:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:35 INFO - Downloading/unpacking psutil>=3.1.1 16:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:37 INFO - Installing collected packages: psutil 16:17:37 INFO - Running setup.py install for psutil 16:17:37 INFO - building 'psutil._psutil_linux' extension 16:17: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:17:37 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:17:37 INFO - building 'psutil._psutil_posix' extension 16:17:37 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:17:37 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:17:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:37 INFO - Successfully installed psutil 16:17:37 INFO - Cleaning up... 16:17:37 INFO - Return code: 0 16:17:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:17:37 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:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:37 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:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:37 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:37 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:17:37 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:17:37 INFO - Using env: {'DISPLAY': ':0', 16:17:37 INFO - 'HOME': '/home/cltbld', 16:17:37 INFO - 'LANG': 'en_US.UTF-8', 16:17:37 INFO - 'LANGUAGE': 'en_US:en', 16:17:37 INFO - 'LOGNAME': 'cltbld', 16:17:37 INFO - 'MAIL': '/var/mail/cltbld', 16:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:37 INFO - 'MOZ_NO_REMOTE': '1', 16:17:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:37 INFO - 'NO_EM_RESTART': '1', 16:17:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:37 INFO - 'PWD': '/builds/slave/test', 16:17:37 INFO - 'SHELL': '/bin/bash', 16:17:37 INFO - 'SHLVL': '1', 16:17:37 INFO - 'TERM': 'linux', 16:17:37 INFO - 'TMOUT': '86400', 16:17:37 INFO - 'USER': 'cltbld', 16:17:37 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:37 INFO - '_': '/tools/buildbot/bin/python'} 16:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:17:37 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:17:37 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:17:37 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:17:37 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:17:37 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:17:37 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:17:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:39 INFO - Installing collected packages: mozsystemmonitor 16:17:39 INFO - Running setup.py install for mozsystemmonitor 16:17:39 INFO - Successfully installed mozsystemmonitor 16:17:39 INFO - Cleaning up... 16:17:39 INFO - Return code: 0 16:17:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:39 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:39 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:17:39 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:17:39 INFO - Using env: {'DISPLAY': ':0', 16:17:39 INFO - 'HOME': '/home/cltbld', 16:17:39 INFO - 'LANG': 'en_US.UTF-8', 16:17:39 INFO - 'LANGUAGE': 'en_US:en', 16:17:39 INFO - 'LOGNAME': 'cltbld', 16:17:39 INFO - 'MAIL': '/var/mail/cltbld', 16:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:39 INFO - 'MOZ_NO_REMOTE': '1', 16:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:39 INFO - 'NO_EM_RESTART': '1', 16:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:39 INFO - 'PWD': '/builds/slave/test', 16:17:39 INFO - 'SHELL': '/bin/bash', 16:17:39 INFO - 'SHLVL': '1', 16:17:39 INFO - 'TERM': 'linux', 16:17:39 INFO - 'TMOUT': '86400', 16:17:39 INFO - 'USER': 'cltbld', 16:17:39 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:39 INFO - '_': '/tools/buildbot/bin/python'} 16:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:17:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:17:42 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:17:42 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:17:42 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:17:42 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:17:42 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:17:42 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:17:42 INFO - Downloading functools32-3.2.3-2.tar.gz 16:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:17:42 INFO - warning: no files found matching '*.txt' 16:17:42 INFO - no previously-included directories found matching 'build' 16:17:42 INFO - no previously-included directories found matching 'dist' 16:17:42 INFO - no previously-included directories found matching '.git*' 16:17:42 INFO - Installing collected packages: jsonschema, functools32 16:17:42 INFO - Running setup.py install for functools32 16:17:42 INFO - warning: no files found matching '*.txt' 16:17:42 INFO - no previously-included directories found matching 'build' 16:17:42 INFO - no previously-included directories found matching 'dist' 16:17:42 INFO - no previously-included directories found matching '.git*' 16:17:42 INFO - Successfully installed jsonschema functools32 16:17:42 INFO - Cleaning up... 16:17:42 INFO - Return code: 0 16:17:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:42 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:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:42 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:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:42 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:42 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:17:42 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:17:42 INFO - Using env: {'DISPLAY': ':0', 16:17:42 INFO - 'HOME': '/home/cltbld', 16:17:42 INFO - 'LANG': 'en_US.UTF-8', 16:17:42 INFO - 'LANGUAGE': 'en_US:en', 16:17:42 INFO - 'LOGNAME': 'cltbld', 16:17:42 INFO - 'MAIL': '/var/mail/cltbld', 16:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:42 INFO - 'MOZ_NO_REMOTE': '1', 16:17:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:42 INFO - 'NO_EM_RESTART': '1', 16:17:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:42 INFO - 'PWD': '/builds/slave/test', 16:17:42 INFO - 'SHELL': '/bin/bash', 16:17:42 INFO - 'SHLVL': '1', 16:17:42 INFO - 'TERM': 'linux', 16:17:42 INFO - 'TMOUT': '86400', 16:17:42 INFO - 'USER': 'cltbld', 16:17:42 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:42 INFO - '_': '/tools/buildbot/bin/python'} 16:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:42 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:17:42 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:17:42 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:17:42 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:17:42 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:17:42 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:17:44 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17: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:17: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:17: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:17: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:17: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:17: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:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:45 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:17:45 INFO - Downloading docopt-0.6.1.tar.gz 16:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:45 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:45 INFO - Running setup.py install for blobuploader 16:17:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:45 INFO - Running setup.py install for requests 16:17:45 INFO - Running setup.py install for docopt 16:17:45 INFO - Successfully installed blobuploader requests docopt 16:17:45 INFO - Cleaning up... 16:17:45 INFO - Return code: 0 16:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:45 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:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:45 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:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:45 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:45 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:17:45 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:17:45 INFO - Using env: {'DISPLAY': ':0', 16:17:45 INFO - 'HOME': '/home/cltbld', 16:17:45 INFO - 'LANG': 'en_US.UTF-8', 16:17:45 INFO - 'LANGUAGE': 'en_US:en', 16:17:45 INFO - 'LOGNAME': 'cltbld', 16:17:45 INFO - 'MAIL': '/var/mail/cltbld', 16:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:45 INFO - 'MOZ_NO_REMOTE': '1', 16:17:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:45 INFO - 'NO_EM_RESTART': '1', 16:17:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:45 INFO - 'PWD': '/builds/slave/test', 16:17:45 INFO - 'SHELL': '/bin/bash', 16:17:45 INFO - 'SHLVL': '1', 16:17:45 INFO - 'TERM': 'linux', 16:17:45 INFO - 'TMOUT': '86400', 16:17:45 INFO - 'USER': 'cltbld', 16:17:45 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:45 INFO - '_': '/tools/buildbot/bin/python'} 16:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:46 INFO - Running setup.py (path:/tmp/pip-Ep34AG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:46 INFO - Running setup.py (path:/tmp/pip-Odpy1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:46 INFO - Running setup.py (path:/tmp/pip-GqeID2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:46 INFO - Running setup.py (path:/tmp/pip-GvI9PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:46 INFO - Running setup.py (path:/tmp/pip-UplvMl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:46 INFO - Running setup.py (path:/tmp/pip-AmDXiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:46 INFO - Running setup.py (path:/tmp/pip-BuQeDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:46 INFO - Running setup.py (path:/tmp/pip-2ltRdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:46 INFO - Running setup.py (path:/tmp/pip-VRfylb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:46 INFO - Running setup.py (path:/tmp/pip-tBxQpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:46 INFO - Running setup.py (path:/tmp/pip-C0u4TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:46 INFO - Running setup.py (path:/tmp/pip-bkuviA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:47 INFO - Running setup.py (path:/tmp/pip-Smo4Hj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:47 INFO - Running setup.py (path:/tmp/pip-xlEBQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:47 INFO - Running setup.py (path:/tmp/pip-Syt6mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:47 INFO - Running setup.py (path:/tmp/pip-GvAnKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:47 INFO - Running setup.py (path:/tmp/pip-U4qw7I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:47 INFO - Running setup.py install for manifestparser 16:17:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Running setup.py install for mozcrash 16:17:47 INFO - Running setup.py install for mozdebug 16:17:47 INFO - Running setup.py install for mozdevice 16:17:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:17:47 INFO - Running setup.py install for mozfile 16:17:47 INFO - Running setup.py install for mozhttpd 16:17:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for mozinfo 16:17:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for mozInstall 16:17:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for mozleak 16:17:48 INFO - Running setup.py install for mozlog 16:17:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for moznetwork 16:17:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for mozprocess 16:17:48 INFO - Running setup.py install for mozprofile 16:17:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for mozrunner 16:17:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:17:48 INFO - Running setup.py install for mozscreenshot 16:17:48 INFO - Running setup.py install for moztest 16:17:49 INFO - Running setup.py install for mozversion 16:17:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:17:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:17:49 INFO - Cleaning up... 16:17:49 INFO - Return code: 0 16:17:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:49 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:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:49 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:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:49 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:49 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:17:49 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:17:49 INFO - Using env: {'DISPLAY': ':0', 16:17:49 INFO - 'HOME': '/home/cltbld', 16:17:49 INFO - 'LANG': 'en_US.UTF-8', 16:17:49 INFO - 'LANGUAGE': 'en_US:en', 16:17:49 INFO - 'LOGNAME': 'cltbld', 16:17:49 INFO - 'MAIL': '/var/mail/cltbld', 16:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:49 INFO - 'MOZ_NO_REMOTE': '1', 16:17:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:49 INFO - 'NO_EM_RESTART': '1', 16:17:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:49 INFO - 'PWD': '/builds/slave/test', 16:17:49 INFO - 'SHELL': '/bin/bash', 16:17:49 INFO - 'SHLVL': '1', 16:17:49 INFO - 'TERM': 'linux', 16:17:49 INFO - 'TMOUT': '86400', 16:17:49 INFO - 'USER': 'cltbld', 16:17:49 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:49 INFO - '_': '/tools/buildbot/bin/python'} 16:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:49 INFO - Running setup.py (path:/tmp/pip-F2AA2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:49 INFO - Running setup.py (path:/tmp/pip-1yrw72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:49 INFO - Running setup.py (path:/tmp/pip-LKZRWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:49 INFO - Running setup.py (path:/tmp/pip-d2weqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:49 INFO - Running setup.py (path:/tmp/pip-k65WxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:49 INFO - Running setup.py (path:/tmp/pip-MqJfHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:49 INFO - Running setup.py (path:/tmp/pip-q9P07c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:49 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:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:49 INFO - Running setup.py (path:/tmp/pip-OV7a0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:50 INFO - Running setup.py (path:/tmp/pip-r97h1h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:50 INFO - Running setup.py (path:/tmp/pip-ya_69B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:50 INFO - Running setup.py (path:/tmp/pip-fwFe1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:50 INFO - Running setup.py (path:/tmp/pip-djBeoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:50 INFO - Running setup.py (path:/tmp/pip-0kymWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:50 INFO - Running setup.py (path:/tmp/pip-O2NppA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:50 INFO - Running setup.py (path:/tmp/pip-ZQ83Zm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:50 INFO - Running setup.py (path:/tmp/pip-YslMMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:50 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:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:50 INFO - Running setup.py (path:/tmp/pip-vFnBlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:50 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:17:52 INFO - Downloading blessings-1.6.tar.gz 16:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:52 INFO - Installing collected packages: blessings 16:17:52 INFO - Running setup.py install for blessings 16:17:52 INFO - Successfully installed blessings 16:17:52 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:53 INFO - Reading from file tmpfile_stdout 16:17:53 INFO - Current package versions: 16:17:53 INFO - argparse == 1.2.1 16:17:53 INFO - blessings == 1.6 16:17:53 INFO - blobuploader == 1.2.4 16:17:53 INFO - docopt == 0.6.1 16:17:53 INFO - functools32 == 3.2.3-2 16:17:53 INFO - jsonschema == 2.5.1 16:17:53 INFO - manifestparser == 1.1 16:17:53 INFO - mozInstall == 1.12 16:17:53 INFO - mozcrash == 0.17 16:17:53 INFO - mozdebug == 0.1 16:17:53 INFO - mozdevice == 0.48 16:17:53 INFO - mozfile == 1.2 16:17:53 INFO - mozhttpd == 0.7 16:17:53 INFO - mozinfo == 0.9 16:17:53 INFO - mozleak == 0.1 16:17:53 INFO - mozlog == 3.2 16:17:53 INFO - moznetwork == 0.27 16:17:53 INFO - mozprocess == 0.23 16:17:53 INFO - mozprofile == 0.28 16:17:53 INFO - mozrunner == 6.12 16:17:53 INFO - mozscreenshot == 0.1 16:17:53 INFO - mozsystemmonitor == 0.3 16:17:53 INFO - moztest == 0.7 16:17:53 INFO - mozversion == 1.4 16:17:53 INFO - psutil == 3.1.1 16:17:53 INFO - requests == 1.2.3 16:17:53 INFO - wsgiref == 0.1.2 16:17:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:53 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:17:53 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:17:53 INFO - Using env: {'DISPLAY': ':0', 16:17:53 INFO - 'HOME': '/home/cltbld', 16:17:53 INFO - 'LANG': 'en_US.UTF-8', 16:17:53 INFO - 'LANGUAGE': 'en_US:en', 16:17:53 INFO - 'LOGNAME': 'cltbld', 16:17:53 INFO - 'MAIL': '/var/mail/cltbld', 16:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:53 INFO - 'MOZ_NO_REMOTE': '1', 16:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:53 INFO - 'NO_EM_RESTART': '1', 16:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:53 INFO - 'PWD': '/builds/slave/test', 16:17:53 INFO - 'SHELL': '/bin/bash', 16:17:53 INFO - 'SHLVL': '1', 16:17:53 INFO - 'TERM': 'linux', 16:17:53 INFO - 'TMOUT': '86400', 16:17:53 INFO - 'USER': 'cltbld', 16:17:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:53 INFO - '_': '/tools/buildbot/bin/python'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 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:17:53 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 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 0x154e030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b18a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x180f180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, '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': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', '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:17:53 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:17:53 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:17:53 INFO - Using env: {'DISPLAY': ':0', 16:17:53 INFO - 'HOME': '/home/cltbld', 16:17:53 INFO - 'LANG': 'en_US.UTF-8', 16:17:53 INFO - 'LANGUAGE': 'en_US:en', 16:17:53 INFO - 'LOGNAME': 'cltbld', 16:17:53 INFO - 'MAIL': '/var/mail/cltbld', 16:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:53 INFO - 'MOZ_NO_REMOTE': '1', 16:17:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:53 INFO - 'NO_EM_RESTART': '1', 16:17:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:53 INFO - 'PWD': '/builds/slave/test', 16:17:53 INFO - 'SHELL': '/bin/bash', 16:17:53 INFO - 'SHLVL': '1', 16:17:53 INFO - 'TERM': 'linux', 16:17:53 INFO - 'TMOUT': '86400', 16:17:53 INFO - 'USER': 'cltbld', 16:17:53 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:53 INFO - '_': '/tools/buildbot/bin/python'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:17:53 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Running post-action listener: _resource_record_post_action 16:17:53 INFO - Running post-action listener: _start_resource_monitoring 16:17:53 INFO - Starting resource monitoring. 16:17:53 INFO - ##### 16:17:53 INFO - ##### Finished create-virtualenv step (success) 16:17:53 INFO - ##### 16:17:53 INFO - ##### 16:17:53 INFO - ##### Running install step. 16:17:53 INFO - ##### 16:17:53 INFO - Running pre-action listener: _resource_record_pre_action 16:17:53 INFO - Running main action method: install 16:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:53 INFO - Reading from file tmpfile_stdout 16:17:53 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:53 INFO - Reading from file tmpfile_stdout 16:17:53 INFO - Output received: 16:17:53 INFO - Usage: mozinstall [options] installer 16:17:53 INFO - Options: 16:17:53 INFO - -h, --help show this help message and exit 16:17:53 INFO - -d DEST, --destination=DEST 16:17:53 INFO - Directory to install application into. [default: 16:17:53 INFO - "/builds/slave/test"] 16:17:53 INFO - --app=APP Application being installed. [default: firefox] 16:17:53 INFO - mkdir: /builds/slave/test/build/application 16:17:53 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:17:53 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:18:10 INFO - Reading from file tmpfile_stdout 16:18:10 INFO - Output received: 16:18:10 INFO - /builds/slave/test/build/application/firefox/firefox 16:18:10 INFO - Running post-action listener: _resource_record_post_action 16:18:10 INFO - ##### 16:18:10 INFO - ##### Finished install step (success) 16:18:10 INFO - ##### 16:18:10 INFO - ##### 16:18:10 INFO - ##### Running run-tests step. 16:18:10 INFO - ##### 16:18:10 INFO - Running pre-action listener: _resource_record_pre_action 16:18:10 INFO - Running main action method: run_tests 16:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:10 INFO - Python 2.7.3 16:18:10 INFO - Return code: 0 16:18:10 INFO - grabbing minidump binary from tooltool 16:18:10 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:18:10 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 0x180d3b0>, '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 0x180e2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x180e740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:10 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:18:10 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:18:10 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:18:10 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:10 INFO - Return code: 0 16:18:10 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:18:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:18:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:10 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/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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:18:10 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/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-053 --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:18:10 INFO - Using env: {'DISPLAY': ':0', 16:18:10 INFO - 'HOME': '/home/cltbld', 16:18:10 INFO - 'LANG': 'en_US.UTF-8', 16:18:10 INFO - 'LANGUAGE': 'en_US:en', 16:18:10 INFO - 'LOGNAME': 'cltbld', 16:18:10 INFO - 'MAIL': '/var/mail/cltbld', 16:18:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:10 INFO - 'MOZ_NO_REMOTE': '1', 16:18:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:10 INFO - 'NO_EM_RESTART': '1', 16:18:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:10 INFO - 'PWD': '/builds/slave/test', 16:18:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:10 INFO - 'SHELL': '/bin/bash', 16:18:10 INFO - 'SHLVL': '1', 16:18:10 INFO - 'TERM': 'linux', 16:18:10 INFO - 'TMOUT': '86400', 16:18:10 INFO - 'USER': 'cltbld', 16:18:10 INFO - 'XDG_SESSION_COOKIE': '920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277', 16:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:10 INFO - '_': '/tools/buildbot/bin/python'} 16:18:10 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/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-053', '--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:18:10 INFO - mozversion application_buildid: 20160708145625 16:18:10 INFO - mozversion application_changeset: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa 16:18:10 INFO - mozversion application_display_name: Nightly 16:18:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:10 INFO - mozversion application_name: Firefox 16:18:10 INFO - mozversion application_remotingname: firefox 16:18:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:18:10 INFO - mozversion application_vendor: Mozilla 16:18:10 INFO - mozversion application_version: 50.0a1 16:18:10 INFO - mozversion platform_buildid: 20160708145625 16:18:10 INFO - mozversion platform_changeset: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa 16:18:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:18:10 INFO - mozversion platform_version: 50.0a1 16:18:10 INFO - using testdate: 1468019890 16:18:10 INFO - actual date: 1468019890 16:18:10 INFO - starting webserver on 'localhost:36999' 16:18:10 INFO - SUITE-START | Running 2 tests 16:18:10 INFO - TEST-START | dromaeo_css 16:18:10 INFO - operating with platform_type : linux_ 16:18:10 INFO - Initialising browser for dromaeo_css test... 16:18:10 INFO - TEST-INFO | started process 27501 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvrfFWz/profile http://localhost:36999/getInfo.html) 16:18:15 INFO - PROCESS | 27501 | __metrics Screen width/height:1600/1200 16:18:15 INFO - PROCESS | 27501 | colorDepth:24 16:18:15 INFO - PROCESS | 27501 | Browser inner width/height: 1024/697 16:18:15 INFO - PROCESS | 27501 | __metrics 16:18:16 INFO - PROCESS | 27501 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:17 INFO - TEST-INFO | 27501: exit 0 16:18:17 INFO - Browser initialized. 16:18:17 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:17 INFO - TEST-INFO | started process 27581 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpvrfFWz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:29 INFO - PROCESS | 27581 | RSS: Main: 158957568 16:18:29 INFO - PROCESS | 27581 | 16:19:20 INFO - PROCESS | 27581 | 1468019960725 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:21:32 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:36999/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36999/tests/dromaeo/cssquery-ext.html) 16:21:33 INFO - PROCESS | 27581 | RSS: Main: 266457088 16:21:33 INFO - PROCESS | 27581 | 16:24:36 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:36999/tests/dromaeo/cssquery-ext.html (next: http://localhost:36999/tests/dromaeo/cssquery-jquery.html) 16:24:36 INFO - PROCESS | 27581 | RSS: Main: 249049088 16:24:36 INFO - PROCESS | 27581 | 16:27:39 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:36999/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36999/tests/dromaeo/cssquery-mootools.html) 16:27:39 INFO - PROCESS | 27581 | RSS: Main: 250753024 16:27:39 INFO - PROCESS | 27581 | 16:30:41 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:36999/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36999/tests/dromaeo/cssquery-prototype.html) 16:30:42 INFO - PROCESS | 27581 | RSS: Main: 240373760 16:30:42 INFO - PROCESS | 27581 | 16:33:45 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:36999/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36999/tests/dromaeo/cssquery-yui.html) 16:33:46 INFO - PROCESS | 27581 | RSS: Main: 218755072 16:33:46 INFO - PROCESS | 27581 | 16:36:48 INFO - PROCESS | 27581 | Cycle 1(1): loaded http://localhost:36999/tests/dromaeo/cssquery-yui.html (next: http://localhost:36999/tests/dromaeo/cssquery-dojo.html) 16:36:49 INFO - PROCESS | 27581 | RSS: Main: 297406464 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | __start_tp_report 16:36:49 INFO - PROCESS | 27581 | _x_x_mozilla_page_load 16:36:49 INFO - PROCESS | 27581 | _x_x_mozilla_page_load_details 16:36:49 INFO - PROCESS | 27581 | |i|pagename|runs| 16:36:49 INFO - PROCESS | 27581 | |0;dojo.html;2550.540902637525;2555.4633526959337;2589.98705006475;2593.507233625611;2599.246218596607;4540.432445944257;4622.86131416058;4639.118567472178;4681.508441613629;4684.765761711918;4626.907461850769;4651.883702907429;4677.4854765975715;4688.85933421997;4702.882427939303;4296.849610263625;4315.870523884289;4319.244132276839;4341.826326946918;4463.6429085673235;4361.236783562864;4406.867793966187;4408.294672852328;4441.378207051015;4447.510773814878;4201;4372.038151606674;4391.780410979438;4413.029133590605;4421.513633500313;4239.936400953989;4241.088166044316;4245.532991370947;4267.231898258312;4307.504636966758;4204.747715137071;4261.296886013804;4270.252705776507;4284.207421627009;4325.632321252666;4488.955110448886;4602.240630295997;4641.675082744209;4692.389989301369;4711.8350857719815;4929.334539837112;4990.30135780991;4996.9500304996845;5020.7740651670765;5069;5055.090083784918;5060.873478163038;5069.467705890902;5072.467390923936;5088.567471764904;4518.887027824265;4731;4744.4069491313585;4761.904761904777;4788.9521104788855;5061.645684802029;5099.821506247264;5118.974405128025;5130.563902068366;5163.277141200235;4896.828610998669;4900.509949005071;4939.580135688507;4944.9258261126115;4949.232868905326;4889.4377146627885;4970.950290497049;5034.773435195425;5038.84883453497;5046.722430266371;4978.377702787152;4980.477049909779;4994.150994330972;5008.724520151354;5050.292958985745;4939.48135445773;4964.056829202512;4965.776540055706;4984.950150498448;5026.29631851541;4218.831246750157;4228.23891699497;4268.487781466182;4322.740635561876;4359.346098085249;3665.9735274123286;3678.7240956928335;3689.040849379127;3726.2920045191327;3765.9246815063543;4237.936430953539;4247.5964783345535;4249.235137675249;4292.935605965913;4293.5706429356815;4903.5586797188425;4964.478729733398;4978.178600530944;4986.775595098156;5011.6241281904;4293.291606884892;4317.633001194871;4326.783660816946;4352.717073390283;4359.760213188305;4537.954620453753;4542.409486766699;4544.613707834805;4555.293929440942;4589.747563883951;4517.41273634432;4643.326717625858;4701.106789709944;4702.905941881211;4741.715497070118;5037.924431133462;5060.468650791614;5066.59467242627;5074.898502029865;5124.052050370544;4707.599854012447;4709.9529004710885;4715.976420117878;4718.150732868116;4762.238041913277;4832.106060378701;4940.258961155855;4983.6760610560195;5019.548240658358;5035.320231768665;5078.949210507995;5088.185890257541;5096.362954630671;5100.081985242692;5141.2288156776785;4823.131836269506;4921.803127874845;4965.677230979974;4965.875853103701;4967.975160124176;4792.7603619819565;4824.782884770124;4854.05345957549;4855.077535268288;4884.267359896044;4770.833020844254;4800.135975524439;4810.5670489656095;4853.781579828846;4860.125177467949;4824.469308376006;4899.799549110491;4903.289023091703;4909.9263511048075;4944.456109827845;4727.456342520517;4783.665143440065;4785.234362502122;4831.033793241296;4855.684380515255 16:36:49 INFO - PROCESS | 27581 | |1;ext.html;570.2387312937232;586.0418216216485;586.3842964886868;588.3968931844856;597.4652685846168;27349;29919.252018699543;43271.56728432719;44570.55429445705;44616;41795;42240.57759422405;42253.15493690124;42305;42333.7883310583;7596.734114306;7812.80467988302;7951.72168974086;7959.602019898982;7962.840743185133;7557.5465472071855;7785.844283114335;7786.80532986677;9958.402495850272;10248.846267305997;10289.331193472437;10294.176465882749;10334.844977325347;10364.481775911212;10369.740756481073;10145.391276523394;10194.59221631134;10208.438535880523;10217.948910255438;10223.233257505643;7622.771316860503;9889.357191782512;10271.28101032928;10273.178145748323;10607.628732994308;39860.80069599662;40062.198756024714;40149;40241.59758402408;40254.194916101806;3081.8613162407746;3102.813831170137;3149.826759528225;3301.8018918864705;3455.4644030175364;29123.271918202;29350.706492935224;29447.411051778843;29538.113856584336;29773.70226297731;3432.5537680101675;3544.2202715402827;3562.69717074049;3595.3168897909313;3646.562412510489;268206;269241;269305;269948;270047;32218.516722249184;33261.16847078794;52127.21809172865;52681.736591316796;52765.208521872206;34324.313513730085;34403.827980860435;34422;34438.82780586081;34497.482537761956;13585.796213056812;13729.519466818614;13730.176189428666;13730.176189428666;13739.656508587364;10123.797524049625;10716.73208169802;10759.623413180501;10774.515146818412;10788.784224315626;7824.765257042298;8066.475679080841;8090.352771778362;8106.108328083906;8134.959325203336;3100.798448100866;3132.592762940849;3147.1660010097344;3170.223295292668;3192.409404260219;726.3027493606091;781.5232708048086;842.5155535567073;848.0755975986204;882.9646814127491;25326.457912961236;26958.5956210657;28004.579931301047;28249.15252542427;28544.571831422545;867.2454964181203;889.0265159650173;890.2566357091771;914.977125571866;954.9924829305149;13540.526081587097;13649.522266720616;13658.248796316098;13730.794038089436;13900.721985560434;403761;403839;404326;405314;407414;46911.765441172574;47377.76311118422;47386.289205661946;47415.28877066847;47426.05147897023;1045.0855501436242;1098.0940723902813;1158.0735411670462;1170.2159553099443;1190.1549899571228;24968.250952471455;27877.58183627288;27970.58044129299;28494;28574.28514297107;47507;48849.75575122102;48890;53101.7344913273;53107.20339194838;373200;379087;380237;380244;380258;33200.335993279514;39054.2189156221;39660.20679586334;39968.4004739935;40592.39111413272;42457.36313955355;42630.14739705127;43560.78219608882;44029;44340.778296108314;36146.27707445889;37091;37624.247515049;37628.623713762514;37788;37116.628833711315;37357;37681.24637507289;37811.243775124894;37940.62059379371;34504.65495345115;34936.825315873255;35503.28993420066;35806.82096589501;35832.104197395274;32270.35459290848;33166.66833331732;33542.16144596405;33942.66057339494;34040.31919361549;24696.876515617307;24797.504049918538;25032.249032529053;25248;25350.49299014046 16:36:49 INFO - PROCESS | 27581 | |2;jquery.html;346.9427544455165;367.43598576185553;371.17969287873797;372.98881033568983;381.6755757606035;13275.137116087448;13841.792373114398;13970.30148492575;17964.191611377486;17998.370057048007;13578.185308881473;13682.315884205775;13695.931520342408;13809.654758631039;13824.101433406822;5108.438071812097;5174.793008279683;5338.199270109476;5338.813141540047;5501.834944951638;5479.753411096491;5518.33779946407;5520.944790552083;5546.473085056933;5555.555555555546;5502.477264659851;5507.504324610784;5629.155626656014;6062.363451837559;6066;5982.820515384546;6002.789902353418;6005;6017.729202185912;6043.848903777397;4730.810767569293;5020.2720605511995;5024;5094.847154585368;5101.795928162869;19271.614567708566;20093.698594521094;20253.49366265855;20335;20556.177752889867;1943.620993906189;1944.6694062009492;1961.3135402608973;1989.333573252962;2360.3509035015336;9152.633894644207;9378.577963991569;9401.341906066578;12095;13669.794953075712;1736.9131543422777;1744.8604111671036;1778.2797966823457;1790.8746387752863;1827.634473105371;66373.66813165952;70989.29010709879;71993.28006719919;72943.63528182326;73139.26860731377;9365.578548965314;9572.377795443273;10190.694279171637;10201.234907381828;10383.376997380181;13889.583312500641;13965.720685586231;13971.58085257444;14004.50984215568;14036.789448158286;7129.7861064168155;7135.750248741269;7258.528195667264;7293.744718934812;7329.963350183215;6673.766418175341;6739.52823302374;6788.9660551696925;6863.245043045261;6919.965400172967;4402.559744025636;4417.801198946056;4427.446569178853;4441.400410944481;4499.910001800011;1661.4932445603965;1832.5327041604305;1849.694800357926;1864.1983946902963;1882.934097306615;748.8764353881658;815.5962798414822;859.6432480520639;902.3503077783985;917.7613820406609;16898.74651880223;16908;17181.31274749021;17207.311707531557;17484;806.2260230178977;812.8496228197803;821.3224090125551;846.580942433487;849.9192576705203;9539.379940303998;9607.51962401877;9758.609655613696;9760.316777825485;9915.355501892498;388945.05022023426;397528;397574;398030;398722;74398.62800685962;83740;84666.57666711627;85276.57361713152;85869.57065214634;916.8074704312169;931.5575101826578;935.3078721745859;945.0407836046281;955.350361754014;15996.280167392262;16108.355665773237;16177.838221617632;16296;16350.264238109552;37650;39220.80389598034;39292;39759.2048159041;40412;53403.73298133485;55311.46885311437;56358.7182064087;57236.71381643065;57586.712066441076;7649.120351159688;7927.326177274997;7966.243206895336;8034.236747508978;8103.189681031849;11724.355160465915;12209.633710988684;12233.755324893631;12385.566505172275;12392.62822115338;10089;10140.74648133773;10858.565657373932;10881.945590271998;10919.181061420266;10070.647527336509;10556.577736890438;10655.520501577603;10712.250142489951;10815.188860835311;10255.487225638837;10378.740531486774;10436.686899393031;10472.109870660774;10854.29447085937;7854.371650267851;7887.092984306878;7998.520088794691;8060.1133875274845;8263.421560490948;9959.402435853872;10009;10012.799744004933;10041.246906482189;10594.205434592282 16:36:49 INFO - PROCESS | 27581 | |3;mootools.html;1381.1851007905336;1411.879990200833;1474.233398632711;1671.531971048107;1725.8360455756706;7166.9641651791735;7478.663460144293;7548.509346892448;7592.544447333157;7598.468107232489;6616.239132499764;6842.692078856451;6849.9657501712545;7012.43900487961;7472.252774722539;1908.646900323441;2125.819305359046;2187.0704950396016;2193.407779899434;2207.580559693661;2185.562887422514;2202.3282898715884;2206.801387875099;2219.9001044953015;2221.7222847144108;1891.640588288221;2203.559288142378;2205.3383984804573;2211.7898799614013;2212.8451008429415;1900.6103748731546;1918.3189967561455;2065.4113577630305;2160.3518944316643;2160.546285280093;2141.668041453577;2175.1625624134663;2176.92380766674;2180.760116387196;2182.181681869299;7620.733274335427;7673.271039251262;7680.11678656956;7680.961595191989;7684.500507466997;4324.113556720896;4493.168763778712;4493.640508759291;4496;4498.122866041123;2651.6155157502053;2861.5993760873494;2871.4113606710575;2872.0952899836693;2877.5395936650243;835.7367429259764;867.0852250875324;880.2693764175718;883.2139395937621;892.4377642085509;9925.25560582952;10082.495875206283;10088.94955525225;10123.240756943258;10141.797164056752;4040.575739547305;4080.3471444568727;4195;4196.223698615766;4294.377315289265;8833.249173820168;8883.200511953826;8894.510801905828;8894.777630559287;8899.644014239488;4513.706609070466;4519.841805536857;4520.525344838745;4521.570450807168;4530.093981203772;3426.623071462137;4041.8787436376956;4084.101497670508;4093.324601440729;4095.610916962884;3332.8666853325594;3550.094725844893;3655.780653160819;3712;3719.423489359154;1157.4212893553224;1170.631251155883;1190;1216.7748966441059;1226.4971361741643;769.9846003080019;793.5952664141214;808.2362167751419;819.1358117186426;849.2781136034322;4914.410270767459;4986.975065124651;5066.544011039025;5096.031753966735;5183.5593974512585;833.7082021292621;855.922966932979;858.1890113842359;868.1145231863464;868.9261412779985;3398.3882901078046;3411.6076651184935;3422.589289285252;3447.4828775683345;3495.790252584853;9054.954725226331;9344.532773361305;9451.858222126673;9467.763305917408;9476.099770521789;9357.578908949114;9395.906040939502;9407.482588457564;9415.293852961055;9420.858687119699;6985.860282794316;7057.294270572902;7116;7122.430205583541;7125.323094306136;4815.470298267118;4823.662343636052;4847.636427267898;5047.470015648304;5094.7962081516325;8716.607752651273;8864.379493435656;8881.067487913677;8903.73288801337;9003.729888103368;8976.506292153994;9045.68340108093;9057.592408341508;9168.862467062865;9179.357444978787;6174.722137503733;6236;6288.308286088618;6362.363763623601;6506.284308725942;7467.775966721007;7477.4391920605085;7489.251074892467;7532.096148462219;7542.698292068475;6391.137196478416;6625.701843416961;6672.366125218096;6710.261871194067;6789.1852977643;6811.93188068113;6814.420774234246;6823.249442561413;6879.965600171967;6903.965480172567;6044.727987240497;6096.177016102947;6120.7551697931585;6121.112438696453;6123.693815309306;4086.223617512663;4533.569310915458;4612.146752850734;4643.210654188729;4668.369770080995;4401.933970990502;4441.866743997685;4446.177457170434;4547.295169248771;4594.287885377638 16:36:49 INFO - PROCESS | 27581 | |4;prototype.html;276.46504014731494;283.5024531946434;283.98794540679285;285.53457863682195;287.05129546847667;4839.128956787786;4868.294097355886;4877.585405240555;4884.2429423439335;4894.510548945108;4788.90422191556;4817.205161148407;4835.637327200457;4845.58812500937;4849.127157111719;3634.418493041114;3690.926181476369;3720.107174278181;3766.81345376207;3772.3398405279104;3745.325841348556;3756.699464042884;3775.5469343678797;3778.6977041836726;3800.4869342638804;3600.261946301002;3727.58996510385;3739.990202645284;3749.662530372266;3766.8304926278247;3614.4036234021355;3729.899679594499;3743.3636281832155;3754.267917756052;3768.2840260350445;3247.253131779704;3295.340931813647;3295.7692961492703;3302.207470207157;3324.9194011945974;4750.61995040396;4773.976130119362;4775.737334446604;4777.976110119427;4778;4210.7473551587;4274.760319507339;4289.914201715979;4326.978365108154;4330.661442820873;5062.772175252123;5082.644214904958;5089.160288552384;5099.770510327045;5114;3578.320119177348;3579.1410061585293;3583.426651735736;3612.331718632035;3618.095476130967;5168.250603662485;5225.162271356063;5262.4212736179;5298.576113910878;5308.150695888639;3619.7828130312264;3647.854085836537;3653.3058718843336;3658.2866341063236;3670.7430479866152;3620.8189590520897;3712.2204337089515;3721.8599305399503;3722.962770372316;3723.2181241938956;4265.466816647919;4277.550857160016;4289.849855255051;4292.6995110342605;4297.247981603207;4829.275608658659;5005.922542144969;5067.315912351859;5071.033445434462;5101.158308879068;1255.8430196225472;1262.1480500662017;1263.9620811375673;1264.2667252993242;1265.9177153484993;795.769226924197;819.3199644295221;832.9791755206047;849.2526576612541;849.9830003399898;4438.578335058164;4452.732836029849;4460.107978404332;4464.754438505914;4468.955310446919;5118.25785261135;5129.974005198975;5129.9743501282255;5223.608229382811;5223.817166399158;4255.914881702349;4369.300911854151;4373.872681033013;4388.27593447084;4439.911201775946;2742.806879007638;2751.7385848344375;2756.8208066475613;2761.9447611047667;2769.1138835572824;4831.4685384607665;4832.565069143794;4835.709857408567;4838.637102217348;4864.70811751289;5050.823221187241;5257.342832145981;5283.920741188808;5303.28405665238;5317.361916570037;4808.471068182427;4809.206480930678;4809.591184749335;4846.151923413388;4864.318995340655;5121.257417674491;5158.38099428071;5171.3535808024;5179.715115668674;5189.948100519097;4913.63147763912;5003.737851981062;5037.496250374933;5062.392512898328;5085.821996230115;4766.735800494737;4813.374261346143;4821.855344339676;4823.348847905488;4827.42070951488;3602.3875941089555;3605.4051081571247;3607.4949507068504;3616.3852145135916;3619.963800362068;5063.696178229318;5136.563392111712;5162.819301324435;5204.55761260282;5229.581633469389;4952.876178095432;4964.826231081895;4969.82605608802;5008.4240312364545;5023.447420783637;5030.62270329734;5033.144365457808;5035.370578677665;5039;5044.571211447068;4698.439350554025;4891.777055736066;4919.950800491949;4951.529604687549;4980.505848245584;4834.492378300228;4892.730899800546;5075.436904363273;5112.983339829519;5120.539151476385;4988.750562471934;5016.949830501794;5031.320771695774;5061.544460998528;5061.709264137621 16:36:49 INFO - PROCESS | 27581 | |5;yui.html;651.9739210431583;661.3981277037894;664.8836453620615;673.9932600673992;749.6401727170958;12527.498900043998;13181.340932953339;13219;13234.801477977813;13363.398647060882;13075.673108172301;13131.93434032831;13151.934240328808;13162.670933226675;13167.275799831006;619.5291578400426;744.694675183175;755.0976582983351;775.6470805783383;778.7079845058104;769.5575044349494;771.4869611708207;774.0788461730551;776.8019155115437;776.8834674798798;808.8786681997718;809.874469457235;812.273015650992;812.3907069697726;813.056854049303;640.0718957511604;659.2912618934616;659.683351991047;662.1689779326962;800.8398320335926;578.8466056495032;594.940505949406;596.0879853823657;596.4751019103205;598.1775059293471;13328.733425331537;13344.733105337937;13359.866401336056;13362.265075420848;13365.064445488819;12440.502379904792;12507;12713;13001.349932503337;13061.21632702041;11074.723131921766;11165.776684466266;11442.256253343588;11488.77022459546;11809.173357864953;4008.6592639625665;4010.1177740896956;4087.3664581989838;4159.022629682022;4160.126373461577;59323.70338148325;59858.102128468105;59859.10211346833;59873.40126598722;60118.398816011715;10090.899091009143;10230.6419275325;10558.260921735406;10600.946995264974;10624.946875265574;17992.91003545;18089.547761306072;18119.72820407695;18152;18163.909180454015;6377.872442551123;6938.306169383021;6958.165020197609;7012.859742805217;7045.0489183960535;3569.428891377367;3633.1280492682095;3661.0664280608275;3703.185299234164;3730.2353017631863;2715.2397328747984;2795.8462284574543;2796.1471751115696;2810.9156725298276;2811.2971757060736;469.4694994656017;484.4259552430381;485.4562889563711;486.5256375034384;498.7406548594783;505.93675790526186;519.8466452396552;543.4646872830259;568.5764105741249;572.2903599536545;7433;7586.468947173756;7844.607769611496;7848.882266766003;8117.310028647513;473.7252393611697;481.73745308806787;500.97996080157117;558.7820749907539;562.535907876004;3703.6296370362747;3720.1071742781537;3740.4763333133383;3749.643783840531;3787.582395269464;75576;77658.22341776623;100355.49822250842;103289;104264;67041.66479167574;68249.31750682529;69516.4386849051;80206.59896700479;80808.59595701983;45613.315800263685;72376.63811680907;74098.62950685211;75305.62347188339;76739;8387.161283871565;8548.187922147636;8688.782780430287;8806.779830504289;8880.73357799267;43246.567534324255;44449;44521.777391114134;45172.096558069316;46271.30593041174;56700.432995671166;62248.68875655593;65356;65967.34032659612;67789;11442.828357574477;11563.710907227387;11636.243644163102;11757;12207.023438125107;12212.816807748068;12724.491020359452;13228.470861165446;13333.866661333263;13917.02580819333;11473.196876218919;11754.647360578854;11987;12367.93816030914;12378;12086.637400877988;12307.323097229739;12429.440675169459;12467.127301088836;12476.251424914171;11283.548658053585;11478.824115595417;11622.593209237637;11999.520019199483;12016;9806.460644664612;10167.796644067224;10356.067953884187;10405.583776649151;10461.895381046092;9286.489243091424;9493.952530237304;9832.55753491109;10222.846657299997;10380.792384152124 16:36:49 INFO - PROCESS | 27581 | __end_tp_report 16:36:49 INFO - PROCESS | 27581 | __start_cc_report 16:36:49 INFO - PROCESS | 27581 | _x_x_mozilla_cycle_collect,1081 16:36:49 INFO - PROCESS | 27581 | __end_cc_report 16:36:49 INFO - PROCESS | 27581 | __startTimestamp1468021009032__endTimestamp 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | ------- Summary: start ------- 16:36:49 INFO - PROCESS | 27581 | Number of tests: 6 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | [#0] dojo.html Cycles:165 Average:4632.09 Median:4744.41 stddev:491.77 (10.4%) stddev-sans-first:465.38 16:36:49 INFO - PROCESS | 27581 | Values: 2550.5 2555.5 2590.0 2593.5 2599.2 4540.4 4622.9 4639.1 4681.5 4684.8 4626.9 4651.9 4677.5 4688.9 4702.9 4296.8 4315.9 4319.2 4341.8 4463.6 4361.2 4406.9 4408.3 4441.4 4447.5 4201.0 4372.0 4391.8 4413.0 4421.5 4239.9 4241.1 4245.5 4267.2 4307.5 4204.7 4261.3 4270.3 4284.2 4325.6 4489.0 4602.2 4641.7 4692.4 4711.8 4929.3 4990.3 4997.0 5020.8 5069.0 5055.1 5060.9 5069.5 5072.5 5088.6 4518.9 4731.0 4744.4 4761.9 4789.0 5061.6 5099.8 5119.0 5130.6 5163.3 4896.8 4900.5 4939.6 4944.9 4949.2 4889.4 4971.0 5034.8 5038.8 5046.7 4978.4 4980.5 4994.2 5008.7 5050.3 4939.5 4964.1 4965.8 4985.0 5026.3 4218.8 4228.2 4268.5 4322.7 4359.3 3666.0 3678.7 3689.0 3726.3 3765.9 4237.9 4247.6 4249.2 4292.9 4293.6 4903.6 4964.5 4978.2 4986.8 5011.6 4293.3 4317.6 4326.8 4352.7 4359.8 4538.0 4542.4 4544.6 4555.3 4589.7 4517.4 4643.3 4701.1 4702.9 4741.7 5037.9 5060.5 5066.6 5074.9 5124.1 4707.6 4710.0 4716.0 4718.2 4762.2 4832.1 4940.3 4983.7 5019.5 5035.3 5078.9 5088.2 5096.4 5100.1 5141.2 4823.1 4921.8 4965.7 4965.9 4968.0 4792.8 4824.8 4854.1 4855.1 4884.3 4770.8 4800.1 4810.6 4853.8 4860.1 4824.5 4899.8 4903.3 4909.9 4944.5 4727.5 4783.7 4785.2 4831.0 4855.7 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | [#1] ext.html Cycles:180 Average:49746.81 Median:35819.46 stddev:93993.89 (262.4%) stddev-sans-first:94185.05 16:36:49 INFO - PROCESS | 27581 | Values: 570.2 586.0 586.4 588.4 597.5 27349.0 29919.3 43271.6 44570.6 44616.0 41795.0 42240.6 42253.2 42305.0 42333.8 7596.7 7812.8 7951.7 7959.6 7962.8 7557.5 7785.8 7786.8 9958.4 10248.8 10289.3 10294.2 10334.8 10364.5 10369.7 10145.4 10194.6 10208.4 10217.9 10223.2 7622.8 9889.4 10271.3 10273.2 10607.6 39860.8 40062.2 40149.0 40241.6 40254.2 3081.9 3102.8 3149.8 3301.8 3455.5 29123.3 29350.7 29447.4 29538.1 29773.7 3432.6 3544.2 3562.7 3595.3 3646.6 268206.0 269241.0 269305.0 269948.0 270047.0 32218.5 33261.2 52127.2 52681.7 52765.2 34324.3 34403.8 34422.0 34438.8 34497.5 13585.8 13729.5 13730.2 13730.2 13739.7 10123.8 10716.7 10759.6 10774.5 10788.8 7824.8 8066.5 8090.4 8106.1 8135.0 3100.8 3132.6 3147.2 3170.2 3192.4 726.3 781.5 842.5 848.1 883.0 25326.5 26958.6 28004.6 28249.2 28544.6 867.2 889.0 890.3 915.0 955.0 13540.5 13649.5 13658.2 13730.8 13900.7 403761.0 403839.0 404326.0 405314.0 407414.0 46911.8 47377.8 47386.3 47415.3 47426.1 1045.1 1098.1 1158.1 1170.2 1190.2 24968.3 27877.6 27970.6 28494.0 28574.3 47507.0 48849.8 48890.0 53101.7 53107.2 373200.0 379087.0 380237.0 380244.0 380258.0 33200.3 39054.2 39660.2 39968.4 40592.4 42457.4 42630.1 43560.8 44029.0 44340.8 36146.3 37091.0 37624.2 37628.6 37788.0 37116.6 37357.0 37681.2 37811.2 37940.6 34504.7 34936.8 35503.3 35806.8 35832.1 32270.4 33166.7 33542.2 33942.7 34040.3 24696.9 24797.5 25032.2 25248.0 25350.5 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | [#2] jquery.html Cycles:180 Average:24909.52 Median:4422.62 stddev:65613.23 (1483.6%) stddev-sans-first:65771.37 16:36:49 INFO - PROCESS | 27581 | Values: 346.9 367.4 371.2 373.0 381.7 13275.1 13841.8 13970.3 17964.2 17998.4 13578.2 13682.3 13695.9 13809.7 13824.1 5108.4 5174.8 5338.2 5338.8 5501.8 5479.8 5518.3 5520.9 5546.5 5555.6 5502.5 5507.5 5629.2 6062.4 6066.0 5982.8 6002.8 6005.0 6017.7 6043.8 4730.8 5020.3 5024.0 5094.8 5101.8 19271.6 20093.7 20253.5 20335.0 20556.2 1943.6 1944.7 1961.3 1989.3 2360.4 9152.6 9378.6 9401.3 12095.0 13669.8 1736.9 1744.9 1778.3 1790.9 1827.6 66373.7 70989.3 71993.3 72943.6 73139.3 9365.6 9572.4 10190.7 10201.2 10383.4 13889.6 13965.7 13971.6 14004.5 14036.8 7129.8 7135.8 7258.5 7293.7 7330.0 6673.8 6739.5 6789.0 6863.2 6920.0 4402.6 4417.8 4427.4 4441.4 4499.9 1661.5 1832.5 1849.7 1864.2 1882.9 748.9 815.6 859.6 902.4 917.8 16898.7 16908.0 17181.3 17207.3 17484.0 806.2 812.8 821.3 846.6 849.9 9539.4 9607.5 9758.6 9760.3 9915.4 388945.1 397528.0 397574.0 398030.0 398722.0 74398.6 83740.0 84666.6 85276.6 85869.6 916.8 931.6 935.3 945.0 955.4 15996.3 16108.4 16177.8 16296.0 16350.3 37650.0 39220.8 39292.0 39759.2 40412.0 53403.7 55311.5 56358.7 57236.7 57586.7 7649.1 7927.3 7966.2 8034.2 8103.2 11724.4 12209.6 12233.8 12385.6 12392.6 10089.0 10140.7 10858.6 10881.9 10919.2 10070.6 10556.6 10655.5 10712.3 10815.2 10255.5 10378.7 10436.7 10472.1 10854.3 7854.4 7887.1 7998.5 8060.1 8263.4 9959.4 10009.0 10012.8 10041.2 10594.2 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | [#3] mootools.html Cycles:180 Average:5003.52 Median:5080.67 stddev:2792.97 (55.0%) stddev-sans-first:2787.54 16:36:49 INFO - PROCESS | 27581 | Values: 1381.2 1411.9 1474.2 1671.5 1725.8 7167.0 7478.7 7548.5 7592.5 7598.5 6616.2 6842.7 6850.0 7012.4 7472.3 1908.6 2125.8 2187.1 2193.4 2207.6 2185.6 2202.3 2206.8 2219.9 2221.7 1891.6 2203.6 2205.3 2211.8 2212.8 1900.6 1918.3 2065.4 2160.4 2160.5 2141.7 2175.2 2176.9 2180.8 2182.2 7620.7 7673.3 7680.1 7681.0 7684.5 4324.1 4493.2 4493.6 4496.0 4498.1 2651.6 2861.6 2871.4 2872.1 2877.5 835.7 867.1 880.3 883.2 892.4 9925.3 10082.5 10088.9 10123.2 10141.8 4040.6 4080.3 4195.0 4196.2 4294.4 8833.2 8883.2 8894.5 8894.8 8899.6 4513.7 4519.8 4520.5 4521.6 4530.1 3426.6 4041.9 4084.1 4093.3 4095.6 3332.9 3550.1 3655.8 3712.0 3719.4 1157.4 1170.6 1190.0 1216.8 1226.5 770.0 793.6 808.2 819.1 849.3 4914.4 4987.0 5066.5 5096.0 5183.6 833.7 855.9 858.2 868.1 868.9 3398.4 3411.6 3422.6 3447.5 3495.8 9055.0 9344.5 9451.9 9467.8 9476.1 9357.6 9395.9 9407.5 9415.3 9420.9 6985.9 7057.3 7116.0 7122.4 7125.3 4815.5 4823.7 4847.6 5047.5 5094.8 8716.6 8864.4 8881.1 8903.7 9003.7 8976.5 9045.7 9057.6 9168.9 9179.4 6174.7 6236.0 6288.3 6362.4 6506.3 7467.8 7477.4 7489.3 7532.1 7542.7 6391.1 6625.7 6672.4 6710.3 6789.2 6811.9 6814.4 6823.2 6880.0 6904.0 6044.7 6096.2 6120.8 6121.1 6123.7 4086.2 4533.6 4612.1 4643.2 4668.4 4401.9 4441.9 4446.2 4547.3 4594.3 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | [#4] prototype.html Cycles:180 Average:4175.21 Median:4811.48 stddev:1218.27 (25.3%) stddev-sans-first:1186.02 16:36:49 INFO - PROCESS | 27581 | Values: 276.5 283.5 284.0 285.5 287.1 4839.1 4868.3 4877.6 4884.2 4894.5 4788.9 4817.2 4835.6 4845.6 4849.1 3634.4 3690.9 3720.1 3766.8 3772.3 3745.3 3756.7 3775.5 3778.7 3800.5 3600.3 3727.6 3740.0 3749.7 3766.8 3614.4 3729.9 3743.4 3754.3 3768.3 3247.3 3295.3 3295.8 3302.2 3324.9 4750.6 4774.0 4775.7 4778.0 4778.0 4210.7 4274.8 4289.9 4327.0 4330.7 5062.8 5082.6 5089.2 5099.8 5114.0 3578.3 3579.1 3583.4 3612.3 3618.1 5168.3 5225.2 5262.4 5298.6 5308.2 3619.8 3647.9 3653.3 3658.3 3670.7 3620.8 3712.2 3721.9 3723.0 3723.2 4265.5 4277.6 4289.8 4292.7 4297.2 4829.3 5005.9 5067.3 5071.0 5101.2 1255.8 1262.1 1264.0 1264.3 1265.9 795.8 819.3 833.0 849.3 850.0 4438.6 4452.7 4460.1 4464.8 4469.0 5118.3 5130.0 5130.0 5223.6 5223.8 4255.9 4369.3 4373.9 4388.3 4439.9 2742.8 2751.7 2756.8 2761.9 2769.1 4831.5 4832.6 4835.7 4838.6 4864.7 5050.8 5257.3 5283.9 5303.3 5317.4 4808.5 4809.2 4809.6 4846.2 4864.3 5121.3 5158.4 5171.4 5179.7 5189.9 4913.6 5003.7 5037.5 5062.4 5085.8 4766.7 4813.4 4821.9 4823.3 4827.4 3602.4 3605.4 3607.5 3616.4 3620.0 5063.7 5136.6 5162.8 5204.6 5229.6 4952.9 4964.8 4969.8 5008.4 5023.4 5030.6 5033.1 5035.4 5039.0 5044.6 4698.4 4891.8 4920.0 4951.5 4980.5 4834.5 4892.7 5075.4 5113.0 5120.5 4988.8 5016.9 5031.3 5061.5 5061.7 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - PROCESS | 27581 | [#5] yui.html Cycles:180 Average:17192.50 Median:44485.39 stddev:23852.22 (53.6%) stddev-sans-first:23886.80 16:36:49 INFO - PROCESS | 27581 | Values: 652.0 661.4 664.9 674.0 749.6 12527.5 13181.3 13219.0 13234.8 13363.4 13075.7 13131.9 13151.9 13162.7 13167.3 619.5 744.7 755.1 775.6 778.7 769.6 771.5 774.1 776.8 776.9 808.9 809.9 812.3 812.4 813.1 640.1 659.3 659.7 662.2 800.8 578.8 594.9 596.1 596.5 598.2 13328.7 13344.7 13359.9 13362.3 13365.1 12440.5 12507.0 12713.0 13001.3 13061.2 11074.7 11165.8 11442.3 11488.8 11809.2 4008.7 4010.1 4087.4 4159.0 4160.1 59323.7 59858.1 59859.1 59873.4 60118.4 10090.9 10230.6 10558.3 10600.9 10624.9 17992.9 18089.5 18119.7 18152.0 18163.9 6377.9 6938.3 6958.2 7012.9 7045.0 3569.4 3633.1 3661.1 3703.2 3730.2 2715.2 2795.8 2796.1 2810.9 2811.3 469.5 484.4 485.5 486.5 498.7 505.9 519.8 543.5 568.6 572.3 7433.0 7586.5 7844.6 7848.9 8117.3 473.7 481.7 501.0 558.8 562.5 3703.6 3720.1 3740.5 3749.6 3787.6 75576.0 77658.2 100355.5 103289.0 104264.0 67041.7 68249.3 69516.4 80206.6 80808.6 45613.3 72376.6 74098.6 75305.6 76739.0 8387.2 8548.2 8688.8 8806.8 8880.7 43246.6 44449.0 44521.8 45172.1 46271.3 56700.4 62248.7 65356.0 65967.3 67789.0 11442.8 11563.7 11636.2 11757.0 12207.0 12212.8 12724.5 13228.5 13333.9 13917.0 11473.2 11754.6 11987.0 12367.9 12378.0 12086.6 12307.3 12429.4 12467.1 12476.3 11283.5 11478.8 11622.6 11999.5 12016.0 9806.5 10167.8 10356.1 10405.6 10461.9 9286.5 9494.0 9832.6 10222.8 10380.8 16:36:49 INFO - PROCESS | 27581 | -------- Summary: end -------- 16:36:49 INFO - PROCESS | 27581 | 16:36:49 INFO - TEST-INFO | 27581: exit 0 16:36:49 INFO - TEST-OK | dromaeo_css | took 1119298ms 16:36:49 INFO - TEST-START | kraken 16:36:49 INFO - operating with platform_type : linux_ 16:36:49 INFO - Initialising browser for kraken test... 16:36:49 INFO - TEST-INFO | started process 27772 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnCv9e6/profile http://localhost:36999/getInfo.html) 16:36:54 INFO - PROCESS | 27772 | __metrics Screen width/height:1600/1200 16:36:54 INFO - PROCESS | 27772 | colorDepth:24 16:36:54 INFO - PROCESS | 27772 | Browser inner width/height: 1024/697 16:36:54 INFO - PROCESS | 27772 | __metrics 16:36:55 INFO - PROCESS | 27772 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:36:56 INFO - TEST-INFO | 27772: exit 0 16:36:56 INFO - Browser initialized. 16:36:56 INFO - Running cycle 1/1 for kraken test... 16:36:56 INFO - TEST-INFO | started process 27851 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnCv9e6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:08 INFO - PROCESS | 27851 | RSS: Main: 158855168 16:37:08 INFO - PROCESS | 27851 | 16:37:10 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36999/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:11 INFO - PROCESS | 27851 | RSS: Main: 216879104 16:37:11 INFO - PROCESS | 27851 | 16:37:14 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36999/tests/kraken/driver.html?testName=audio-dft) 16:37:15 INFO - PROCESS | 27851 | RSS: Main: 203816960 16:37:15 INFO - PROCESS | 27851 | 16:37:18 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36999/tests/kraken/driver.html?testName=audio-fft) 16:37:18 INFO - PROCESS | 27851 | RSS: Main: 271380480 16:37:18 INFO - PROCESS | 27851 | 16:37:21 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36999/tests/kraken/driver.html?testName=audio-oscillator) 16:37:21 INFO - PROCESS | 27851 | RSS: Main: 182079488 16:37:21 INFO - PROCESS | 27851 | 16:37:23 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36999/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:24 INFO - PROCESS | 27851 | RSS: Main: 185921536 16:37:24 INFO - PROCESS | 27851 | 16:37:27 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36999/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:28 INFO - PROCESS | 27851 | RSS: Main: 222453760 16:37:28 INFO - PROCESS | 27851 | 16:37:31 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36999/tests/kraken/driver.html?testName=imaging-desaturate) 16:37:32 INFO - PROCESS | 27851 | RSS: Main: 217509888 16:37:32 INFO - PROCESS | 27851 | 16:37:35 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36999/tests/kraken/driver.html?testName=json-parse-financial) 16:37:35 INFO - PROCESS | 27851 | RSS: Main: 220434432 16:37:35 INFO - PROCESS | 27851 | 16:37:37 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36999/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:37:38 INFO - PROCESS | 27851 | RSS: Main: 206094336 16:37:38 INFO - PROCESS | 27851 | 16:37:40 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:40 INFO - PROCESS | 27851 | RSS: Main: 211939328 16:37:40 INFO - PROCESS | 27851 | 16:37:42 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:37:43 INFO - PROCESS | 27851 | RSS: Main: 210784256 16:37:43 INFO - PROCESS | 27851 | 16:37:46 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:37:46 INFO - PROCESS | 27851 | RSS: Main: 224530432 16:37:46 INFO - PROCESS | 27851 | 16:37:49 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:37:50 INFO - PROCESS | 27851 | RSS: Main: 226795520 16:37:50 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | Cycle 1(1): loaded http://localhost:36999/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36999/tests/kraken/driver.html?testName=ai-astar) 16:37:52 INFO - PROCESS | 27851 | RSS: Main: 269393920 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | __start_tp_report 16:37:52 INFO - PROCESS | 27851 | _x_x_mozilla_page_load 16:37:52 INFO - PROCESS | 27851 | _x_x_mozilla_page_load_details 16:37:52 INFO - PROCESS | 27851 | |i|pagename|runs| 16:37:52 INFO - PROCESS | 27851 | |0;ai-astar;105;116;113;112;112;113;112;113;113;112 16:37:52 INFO - PROCESS | 27851 | |1;audio-beat-detection;159;127;130;170;123;123;153;123;161;122 16:37:52 INFO - PROCESS | 27851 | |2;audio-dft;161;162;160;161;163;162;162;163;162;162 16:37:52 INFO - PROCESS | 27851 | |3;audio-fft;75;74;76;114;77;76;75;75;114;76 16:37:52 INFO - PROCESS | 27851 | |4;audio-oscillator;86;85;113;86;85;84;114;85;85;125 16:37:52 INFO - PROCESS | 27851 | |5;imaging-gaussian-blur;117;117;121;116;117;117;117;116;117;117 16:37:52 INFO - PROCESS | 27851 | |6;imaging-darkroom;147;146;147;146;146;146;146;146;146;146 16:37:52 INFO - PROCESS | 27851 | |7;imaging-desaturate;95;95;95;96;95;95;95;95;95;95 16:37:52 INFO - PROCESS | 27851 | |8;json-parse-financial;63;63;64;64;63;63;63;62;63;63 16:37:52 INFO - PROCESS | 27851 | |9;json-stringify-tinderbox;65;64;64;64;65;65;64;64;64;64 16:37:52 INFO - PROCESS | 27851 | |10;stanford-crypto-aes;78;79;87;81;78;77;79;79;79;83 16:37:52 INFO - PROCESS | 27851 | |11;stanford-crypto-ccm;129;127;129;127;138;130;128;130;206;130 16:37:52 INFO - PROCESS | 27851 | |12;stanford-crypto-pbkdf2;169;171;169;170;173;174;168;169;167;168 16:37:52 INFO - PROCESS | 27851 | |13;stanford-crypto-sha256-iterative;66;67;68;66;67;67;67;67;67;66 16:37:52 INFO - PROCESS | 27851 | __end_tp_report 16:37:52 INFO - PROCESS | 27851 | __start_cc_report 16:37:52 INFO - PROCESS | 27851 | _x_x_mozilla_cycle_collect,1888 16:37:52 INFO - PROCESS | 27851 | __end_cc_report 16:37:52 INFO - PROCESS | 27851 | __startTimestamp1468021072481__endTimestamp 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | ------- Summary: start ------- 16:37:52 INFO - PROCESS | 27851 | Number of tests: 14 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.77 (2.4%) stddev-sans-first:1.27 16:37:52 INFO - PROCESS | 27851 | Values: 105.0 116.0 113.0 112.0 112.0 113.0 112.0 113.0 113.0 112.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:141.50 stddev:19.21 (13.6%) stddev-sans-first:18.98 16:37:52 INFO - PROCESS | 27851 | Values: 159.0 127.0 130.0 170.0 123.0 123.0 153.0 123.0 161.0 122.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:0.92 (0.6%) stddev-sans-first:0.93 16:37:52 INFO - PROCESS | 27851 | Values: 161.0 162.0 160.0 161.0 163.0 162.0 162.0 163.0 162.0 162.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.50 stddev:16.25 (21.5%) stddev-sans-first:16.97 16:37:52 INFO - PROCESS | 27851 | Values: 75.0 74.0 76.0 114.0 77.0 76.0 75.0 75.0 114.0 76.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:85.00 stddev:15.87 (18.7%) stddev-sans-first:16.51 16:37:52 INFO - PROCESS | 27851 | Values: 86.0 85.0 113.0 86.0 85.0 84.0 114.0 85.0 85.0 125.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:1.40 (1.2%) stddev-sans-first:1.48 16:37:52 INFO - PROCESS | 27851 | Values: 117.0 117.0 121.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 16:37:52 INFO - PROCESS | 27851 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:37:52 INFO - PROCESS | 27851 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:63.00 stddev:0.57 (0.9%) stddev-sans-first:0.60 16:37:52 INFO - PROCESS | 27851 | Values: 63.0 63.0 64.0 64.0 63.0 63.0 63.0 62.0 63.0 63.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:64.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 16:37:52 INFO - PROCESS | 27851 | Values: 65.0 64.0 64.0 64.0 65.0 65.0 64.0 64.0 64.0 64.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#10] stanford-crypto-aes Cycles:10 Average:80.00 Median:79.00 stddev:2.98 (3.8%) stddev-sans-first:3.07 16:37:52 INFO - PROCESS | 27851 | Values: 78.0 79.0 87.0 81.0 78.0 77.0 79.0 79.0 79.0 83.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#11] stanford-crypto-ccm Cycles:10 Average:137.40 Median:130.00 stddev:24.30 (18.7%) stddev-sans-first:25.59 16:37:52 INFO - PROCESS | 27851 | Values: 129.0 127.0 129.0 127.0 138.0 130.0 128.0 130.0 206.0 130.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:169.50 stddev:2.25 (1.3%) stddev-sans-first:2.37 16:37:52 INFO - PROCESS | 27851 | Values: 169.0 171.0 169.0 170.0 173.0 174.0 168.0 169.0 167.0 168.0 16:37:52 INFO - PROCESS | 27851 | 16:37:52 INFO - PROCESS | 27851 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.63 (0.9%) stddev-sans-first:0.60 16:37:52 INFO - PROCESS | 27851 | Values: 66.0 67.0 68.0 66.0 67.0 67.0 67.0 67.0 67.0 66.0 16:37:52 INFO - PROCESS | 27851 | -------- Summary: end -------- 16:37:52 INFO - PROCESS | 27851 | 16:37:53 INFO - TEST-INFO | 27851: exit 0 16:37:53 INFO - TEST-OK | kraken | took 63731ms 16:37:53 INFO - SUITE-END | took 1183s 16:37:53 INFO - Completed test suite (00:19:43) 16:37:53 INFO - javascript benchmark 16:37:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2550.540902637525, 2555.4633526959337, 2589.98705006475, 2593.507233625611, 2599.246218596607, 4540.432445944257, 4622.86131416058, 4639.118567472178, 4681.508441613629, 4684.765761711918, 4626.907461850769, 4651.883702907429, 4677.4854765975715, 4688.85933421997, 4702.882427939303, 4296.849610263625, 4315.870523884289, 4319.244132276839, 4341.826326946918, 4463.6429085673235, 4361.236783562864, 4406.867793966187, 4408.294672852328, 4441.378207051015, 4447.510773814878, 4201.0, 4372.038151606674, 4391.780410979438, 4413.029133590605, 4421.513633500313, 4239.936400953989, 4241.088166044316, 4245.532991370947, 4267.231898258312, 4307.504636966758, 4204.747715137071, 4261.296886013804, 4270.252705776507, 4284.207421627009, 4325.632321252666, 4488.955110448886, 4602.240630295997, 4641.675082744209, 4692.389989301369, 4711.8350857719815, 4929.334539837112, 4990.30135780991, 4996.9500304996845, 5020.7740651670765, 5069.0, 5055.090083784918, 5060.873478163038, 5069.467705890902, 5072.467390923936, 5088.567471764904, 4518.887027824265, 4731.0, 4744.4069491313585, 4761.904761904777, 4788.9521104788855, 5061.645684802029, 5099.821506247264, 5118.974405128025, 5130.563902068366, 5163.277141200235, 4896.828610998669, 4900.509949005071, 4939.580135688507, 4944.9258261126115, 4949.232868905326, 4889.4377146627885, 4970.950290497049, 5034.773435195425, 5038.84883453497, 5046.722430266371, 4978.377702787152, 4980.477049909779, 4994.150994330972, 5008.724520151354, 5050.292958985745, 4939.48135445773, 4964.056829202512, 4965.776540055706, 4984.950150498448, 5026.29631851541, 4218.831246750157, 4228.23891699497, 4268.487781466182, 4322.740635561876, 4359.346098085249, 3665.9735274123286, 3678.7240956928335, 3689.040849379127, 3726.2920045191327, 3765.9246815063543, 4237.936430953539, 4247.5964783345535, 4249.235137675249, 4292.935605965913, 4293.5706429356815, 4903.5586797188425, 4964.478729733398, 4978.178600530944, 4986.775595098156, 5011.6241281904, 4293.291606884892, 4317.633001194871, 4326.783660816946, 4352.717073390283, 4359.760213188305, 4537.954620453753, 4542.409486766699, 4544.613707834805, 4555.293929440942, 4589.747563883951, 4517.41273634432, 4643.326717625858, 4701.106789709944, 4702.905941881211, 4741.715497070118, 5037.924431133462, 5060.468650791614, 5066.59467242627, 5074.898502029865, 5124.052050370544, 4707.599854012447, 4709.9529004710885, 4715.976420117878, 4718.150732868116, 4762.238041913277, 4832.106060378701, 4940.258961155855, 4983.6760610560195, 5019.548240658358, 5035.320231768665, 5078.949210507995, 5088.185890257541, 5096.362954630671, 5100.081985242692, 5141.2288156776785, 4823.131836269506, 4921.803127874845, 4965.677230979974, 4965.875853103701, 4967.975160124176, 4792.7603619819565, 4824.782884770124, 4854.05345957549, 4855.077535268288, 4884.267359896044, 4770.833020844254, 4800.135975524439, 4810.5670489656095, 4853.781579828846, 4860.125177467949, 4824.469308376006, 4899.799549110491, 4903.289023091703, 4909.9263511048075, 4944.456109827845, 4727.456342520517, 4783.665143440065, 4785.234362502122, 4831.033793241296, 4855.684380515255], "value": 4600.031243010529, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [570.2387312937232, 586.0418216216485, 586.3842964886868, 588.3968931844856, 597.4652685846168, 27349.0, 29919.252018699543, 43271.56728432719, 44570.55429445705, 44616.0, 41795.0, 42240.57759422405, 42253.15493690124, 42305.0, 42333.7883310583, 7596.734114306, 7812.80467988302, 7951.72168974086, 7959.602019898982, 7962.840743185133, 7557.5465472071855, 7785.844283114335, 7786.80532986677, 9958.402495850272, 10248.846267305997, 10289.331193472437, 10294.176465882749, 10334.844977325347, 10364.481775911212, 10369.740756481073, 10145.391276523394, 10194.59221631134, 10208.438535880523, 10217.948910255438, 10223.233257505643, 7622.771316860503, 9889.357191782512, 10271.28101032928, 10273.178145748323, 10607.628732994308, 39860.80069599662, 40062.198756024714, 40149.0, 40241.59758402408, 40254.194916101806, 3081.8613162407746, 3102.813831170137, 3149.826759528225, 3301.8018918864705, 3455.4644030175364, 29123.271918202, 29350.706492935224, 29447.411051778843, 29538.113856584336, 29773.70226297731, 3432.5537680101675, 3544.2202715402827, 3562.69717074049, 3595.3168897909313, 3646.562412510489, 268206.0, 269241.0, 269305.0, 269948.0, 270047.0, 32218.516722249184, 33261.16847078794, 52127.21809172865, 52681.736591316796, 52765.208521872206, 34324.313513730085, 34403.827980860435, 34422.0, 34438.82780586081, 34497.482537761956, 13585.796213056812, 13729.519466818614, 13730.176189428666, 13730.176189428666, 13739.656508587364, 10123.797524049625, 10716.73208169802, 10759.623413180501, 10774.515146818412, 10788.784224315626, 7824.765257042298, 8066.475679080841, 8090.352771778362, 8106.108328083906, 8134.959325203336, 3100.798448100866, 3132.592762940849, 3147.1660010097344, 3170.223295292668, 3192.409404260219, 726.3027493606091, 781.5232708048086, 842.5155535567073, 848.0755975986204, 882.9646814127491, 25326.457912961236, 26958.5956210657, 28004.579931301047, 28249.15252542427, 28544.571831422545, 867.2454964181203, 889.0265159650173, 890.2566357091771, 914.977125571866, 954.9924829305149, 13540.526081587097, 13649.522266720616, 13658.248796316098, 13730.794038089436, 13900.721985560434, 403761.0, 403839.0, 404326.0, 405314.0, 407414.0, 46911.765441172574, 47377.76311118422, 47386.289205661946, 47415.28877066847, 47426.05147897023, 1045.0855501436242, 1098.0940723902813, 1158.0735411670462, 1170.2159553099443, 1190.1549899571228, 24968.250952471455, 27877.58183627288, 27970.58044129299, 28494.0, 28574.28514297107, 47507.0, 48849.75575122102, 48890.0, 53101.7344913273, 53107.20339194838, 373200.0, 379087.0, 380237.0, 380244.0, 380258.0, 33200.335993279514, 39054.2189156221, 39660.20679586334, 39968.4004739935, 40592.39111413272, 42457.36313955355, 42630.14739705127, 43560.78219608882, 44029.0, 44340.778296108314, 36146.27707445889, 37091.0, 37624.247515049, 37628.623713762514, 37788.0, 37116.628833711315, 37357.0, 37681.24637507289, 37811.243775124894, 37940.62059379371, 34504.65495345115, 34936.825315873255, 35503.28993420066, 35806.82096589501, 35832.104197395274, 32270.35459290848, 33166.66833331732, 33542.16144596405, 33942.66057339494, 34040.31919361549, 24696.876515617307, 24797.504049918538, 25032.249032529053, 25248.0, 25350.49299014046], "value": 17190.79017660151, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.9427544455165, 367.43598576185553, 371.17969287873797, 372.98881033568983, 381.6755757606035, 13275.137116087448, 13841.792373114398, 13970.30148492575, 17964.191611377486, 17998.370057048007, 13578.185308881473, 13682.315884205775, 13695.931520342408, 13809.654758631039, 13824.101433406822, 5108.438071812097, 5174.793008279683, 5338.199270109476, 5338.813141540047, 5501.834944951638, 5479.753411096491, 5518.33779946407, 5520.944790552083, 5546.473085056933, 5555.555555555546, 5502.477264659851, 5507.504324610784, 5629.155626656014, 6062.363451837559, 6066.0, 5982.820515384546, 6002.789902353418, 6005.0, 6017.729202185912, 6043.848903777397, 4730.810767569293, 5020.2720605511995, 5024.0, 5094.847154585368, 5101.795928162869, 19271.614567708566, 20093.698594521094, 20253.49366265855, 20335.0, 20556.177752889867, 1943.620993906189, 1944.6694062009492, 1961.3135402608973, 1989.333573252962, 2360.3509035015336, 9152.633894644207, 9378.577963991569, 9401.341906066578, 12095.0, 13669.794953075712, 1736.9131543422777, 1744.8604111671036, 1778.2797966823457, 1790.8746387752863, 1827.634473105371, 66373.66813165952, 70989.29010709879, 71993.28006719919, 72943.63528182326, 73139.26860731377, 9365.578548965314, 9572.377795443273, 10190.694279171637, 10201.234907381828, 10383.376997380181, 13889.583312500641, 13965.720685586231, 13971.58085257444, 14004.50984215568, 14036.789448158286, 7129.7861064168155, 7135.750248741269, 7258.528195667264, 7293.744718934812, 7329.963350183215, 6673.766418175341, 6739.52823302374, 6788.9660551696925, 6863.245043045261, 6919.965400172967, 4402.559744025636, 4417.801198946056, 4427.446569178853, 4441.400410944481, 4499.910001800011, 1661.4932445603965, 1832.5327041604305, 1849.694800357926, 1864.1983946902963, 1882.934097306615, 748.8764353881658, 815.5962798414822, 859.6432480520639, 902.3503077783985, 917.7613820406609, 16898.74651880223, 16908.0, 17181.31274749021, 17207.311707531557, 17484.0, 806.2260230178977, 812.8496228197803, 821.3224090125551, 846.580942433487, 849.9192576705203, 9539.379940303998, 9607.51962401877, 9758.609655613696, 9760.316777825485, 9915.355501892498, 388945.05022023426, 397528.0, 397574.0, 398030.0, 398722.0, 74398.62800685962, 83740.0, 84666.57666711627, 85276.57361713152, 85869.57065214634, 916.8074704312169, 931.5575101826578, 935.3078721745859, 945.0407836046281, 955.350361754014, 15996.280167392262, 16108.355665773237, 16177.838221617632, 16296.0, 16350.264238109552, 37650.0, 39220.80389598034, 39292.0, 39759.2048159041, 40412.0, 53403.73298133485, 55311.46885311437, 56358.7182064087, 57236.71381643065, 57586.712066441076, 7649.120351159688, 7927.326177274997, 7966.243206895336, 8034.236747508978, 8103.189681031849, 11724.355160465915, 12209.633710988684, 12233.755324893631, 12385.566505172275, 12392.62822115338, 10089.0, 10140.74648133773, 10858.565657373932, 10881.945590271998, 10919.181061420266, 10070.647527336509, 10556.577736890438, 10655.520501577603, 10712.250142489951, 10815.188860835311, 10255.487225638837, 10378.740531486774, 10436.686899393031, 10472.109870660774, 10854.29447085937, 7854.371650267851, 7887.092984306878, 7998.520088794691, 8060.1133875274845, 8263.421560490948, 9959.402435853872, 10009.0, 10012.799744004933, 10041.246906482189, 10594.205434592282], "value": 8335.11664853843, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.1851007905336, 1411.879990200833, 1474.233398632711, 1671.531971048107, 1725.8360455756706, 7166.9641651791735, 7478.663460144293, 7548.509346892448, 7592.544447333157, 7598.468107232489, 6616.239132499764, 6842.692078856451, 6849.9657501712545, 7012.43900487961, 7472.252774722539, 1908.646900323441, 2125.819305359046, 2187.0704950396016, 2193.407779899434, 2207.580559693661, 2185.562887422514, 2202.3282898715884, 2206.801387875099, 2219.9001044953015, 2221.7222847144108, 1891.640588288221, 2203.559288142378, 2205.3383984804573, 2211.7898799614013, 2212.8451008429415, 1900.6103748731546, 1918.3189967561455, 2065.4113577630305, 2160.3518944316643, 2160.546285280093, 2141.668041453577, 2175.1625624134663, 2176.92380766674, 2180.760116387196, 2182.181681869299, 7620.733274335427, 7673.271039251262, 7680.11678656956, 7680.961595191989, 7684.500507466997, 4324.113556720896, 4493.168763778712, 4493.640508759291, 4496.0, 4498.122866041123, 2651.6155157502053, 2861.5993760873494, 2871.4113606710575, 2872.0952899836693, 2877.5395936650243, 835.7367429259764, 867.0852250875324, 880.2693764175718, 883.2139395937621, 892.4377642085509, 9925.25560582952, 10082.495875206283, 10088.94955525225, 10123.240756943258, 10141.797164056752, 4040.575739547305, 4080.3471444568727, 4195.0, 4196.223698615766, 4294.377315289265, 8833.249173820168, 8883.200511953826, 8894.510801905828, 8894.777630559287, 8899.644014239488, 4513.706609070466, 4519.841805536857, 4520.525344838745, 4521.570450807168, 4530.093981203772, 3426.623071462137, 4041.8787436376956, 4084.101497670508, 4093.324601440729, 4095.610916962884, 3332.8666853325594, 3550.094725844893, 3655.780653160819, 3712.0, 3719.423489359154, 1157.4212893553224, 1170.631251155883, 1190.0, 1216.7748966441059, 1226.4971361741643, 769.9846003080019, 793.5952664141214, 808.2362167751419, 819.1358117186426, 849.2781136034322, 4914.410270767459, 4986.975065124651, 5066.544011039025, 5096.031753966735, 5183.5593974512585, 833.7082021292621, 855.922966932979, 858.1890113842359, 868.1145231863464, 868.9261412779985, 3398.3882901078046, 3411.6076651184935, 3422.589289285252, 3447.4828775683345, 3495.790252584853, 9054.954725226331, 9344.532773361305, 9451.858222126673, 9467.763305917408, 9476.099770521789, 9357.578908949114, 9395.906040939502, 9407.482588457564, 9415.293852961055, 9420.858687119699, 6985.860282794316, 7057.294270572902, 7116.0, 7122.430205583541, 7125.323094306136, 4815.470298267118, 4823.662343636052, 4847.636427267898, 5047.470015648304, 5094.7962081516325, 8716.607752651273, 8864.379493435656, 8881.067487913677, 8903.73288801337, 9003.729888103368, 8976.506292153994, 9045.68340108093, 9057.592408341508, 9168.862467062865, 9179.357444978787, 6174.722137503733, 6236.0, 6288.308286088618, 6362.363763623601, 6506.284308725942, 7467.775966721007, 7477.4391920605085, 7489.251074892467, 7532.096148462219, 7542.698292068475, 6391.137196478416, 6625.701843416961, 6672.366125218096, 6710.261871194067, 6789.1852977643, 6811.93188068113, 6814.420774234246, 6823.249442561413, 6879.965600171967, 6903.965480172567, 6044.727987240497, 6096.177016102947, 6120.7551697931585, 6121.112438696453, 6123.693815309306, 4086.223617512663, 4533.569310915458, 4612.146752850734, 4643.210654188729, 4668.369770080995, 4401.933970990502, 4441.866743997685, 4446.177457170434, 4547.295169248771, 4594.287885377638], "value": 4040.0218417660003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [276.46504014731494, 283.5024531946434, 283.98794540679285, 285.53457863682195, 287.05129546847667, 4839.128956787786, 4868.294097355886, 4877.585405240555, 4884.2429423439335, 4894.510548945108, 4788.90422191556, 4817.205161148407, 4835.637327200457, 4845.58812500937, 4849.127157111719, 3634.418493041114, 3690.926181476369, 3720.107174278181, 3766.81345376207, 3772.3398405279104, 3745.325841348556, 3756.699464042884, 3775.5469343678797, 3778.6977041836726, 3800.4869342638804, 3600.261946301002, 3727.58996510385, 3739.990202645284, 3749.662530372266, 3766.8304926278247, 3614.4036234021355, 3729.899679594499, 3743.3636281832155, 3754.267917756052, 3768.2840260350445, 3247.253131779704, 3295.340931813647, 3295.7692961492703, 3302.207470207157, 3324.9194011945974, 4750.61995040396, 4773.976130119362, 4775.737334446604, 4777.976110119427, 4778.0, 4210.7473551587, 4274.760319507339, 4289.914201715979, 4326.978365108154, 4330.661442820873, 5062.772175252123, 5082.644214904958, 5089.160288552384, 5099.770510327045, 5114.0, 3578.320119177348, 3579.1410061585293, 3583.426651735736, 3612.331718632035, 3618.095476130967, 5168.250603662485, 5225.162271356063, 5262.4212736179, 5298.576113910878, 5308.150695888639, 3619.7828130312264, 3647.854085836537, 3653.3058718843336, 3658.2866341063236, 3670.7430479866152, 3620.8189590520897, 3712.2204337089515, 3721.8599305399503, 3722.962770372316, 3723.2181241938956, 4265.466816647919, 4277.550857160016, 4289.849855255051, 4292.6995110342605, 4297.247981603207, 4829.275608658659, 5005.922542144969, 5067.315912351859, 5071.033445434462, 5101.158308879068, 1255.8430196225472, 1262.1480500662017, 1263.9620811375673, 1264.2667252993242, 1265.9177153484993, 795.769226924197, 819.3199644295221, 832.9791755206047, 849.2526576612541, 849.9830003399898, 4438.578335058164, 4452.732836029849, 4460.107978404332, 4464.754438505914, 4468.955310446919, 5118.25785261135, 5129.974005198975, 5129.9743501282255, 5223.608229382811, 5223.817166399158, 4255.914881702349, 4369.300911854151, 4373.872681033013, 4388.27593447084, 4439.911201775946, 2742.806879007638, 2751.7385848344375, 2756.8208066475613, 2761.9447611047667, 2769.1138835572824, 4831.4685384607665, 4832.565069143794, 4835.709857408567, 4838.637102217348, 4864.70811751289, 5050.823221187241, 5257.342832145981, 5283.920741188808, 5303.28405665238, 5317.361916570037, 4808.471068182427, 4809.206480930678, 4809.591184749335, 4846.151923413388, 4864.318995340655, 5121.257417674491, 5158.38099428071, 5171.3535808024, 5179.715115668674, 5189.948100519097, 4913.63147763912, 5003.737851981062, 5037.496250374933, 5062.392512898328, 5085.821996230115, 4766.735800494737, 4813.374261346143, 4821.855344339676, 4823.348847905488, 4827.42070951488, 3602.3875941089555, 3605.4051081571247, 3607.4949507068504, 3616.3852145135916, 3619.963800362068, 5063.696178229318, 5136.563392111712, 5162.819301324435, 5204.55761260282, 5229.581633469389, 4952.876178095432, 4964.826231081895, 4969.82605608802, 5008.4240312364545, 5023.447420783637, 5030.62270329734, 5033.144365457808, 5035.370578677665, 5039.0, 5044.571211447068, 4698.439350554025, 4891.777055736066, 4919.950800491949, 4951.529604687549, 4980.505848245584, 4834.492378300228, 4892.730899800546, 5075.436904363273, 5112.983339829519, 5120.539151476385, 4988.750562471934, 5016.949830501794, 5031.320771695774, 5061.544460998528, 5061.709264137621], "value": 3780.948564824647, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.9739210431583, 661.3981277037894, 664.8836453620615, 673.9932600673992, 749.6401727170958, 12527.498900043998, 13181.340932953339, 13219.0, 13234.801477977813, 13363.398647060882, 13075.673108172301, 13131.93434032831, 13151.934240328808, 13162.670933226675, 13167.275799831006, 619.5291578400426, 744.694675183175, 755.0976582983351, 775.6470805783383, 778.7079845058104, 769.5575044349494, 771.4869611708207, 774.0788461730551, 776.8019155115437, 776.8834674798798, 808.8786681997718, 809.874469457235, 812.273015650992, 812.3907069697726, 813.056854049303, 640.0718957511604, 659.2912618934616, 659.683351991047, 662.1689779326962, 800.8398320335926, 578.8466056495032, 594.940505949406, 596.0879853823657, 596.4751019103205, 598.1775059293471, 13328.733425331537, 13344.733105337937, 13359.866401336056, 13362.265075420848, 13365.064445488819, 12440.502379904792, 12507.0, 12713.0, 13001.349932503337, 13061.21632702041, 11074.723131921766, 11165.776684466266, 11442.256253343588, 11488.77022459546, 11809.173357864953, 4008.6592639625665, 4010.1177740896956, 4087.3664581989838, 4159.022629682022, 4160.126373461577, 59323.70338148325, 59858.102128468105, 59859.10211346833, 59873.40126598722, 60118.398816011715, 10090.899091009143, 10230.6419275325, 10558.260921735406, 10600.946995264974, 10624.946875265574, 17992.91003545, 18089.547761306072, 18119.72820407695, 18152.0, 18163.909180454015, 6377.872442551123, 6938.306169383021, 6958.165020197609, 7012.859742805217, 7045.0489183960535, 3569.428891377367, 3633.1280492682095, 3661.0664280608275, 3703.185299234164, 3730.2353017631863, 2715.2397328747984, 2795.8462284574543, 2796.1471751115696, 2810.9156725298276, 2811.2971757060736, 469.4694994656017, 484.4259552430381, 485.4562889563711, 486.5256375034384, 498.7406548594783, 505.93675790526186, 519.8466452396552, 543.4646872830259, 568.5764105741249, 572.2903599536545, 7433.0, 7586.468947173756, 7844.607769611496, 7848.882266766003, 8117.310028647513, 473.7252393611697, 481.73745308806787, 500.97996080157117, 558.7820749907539, 562.535907876004, 3703.6296370362747, 3720.1071742781537, 3740.4763333133383, 3749.643783840531, 3787.582395269464, 75576.0, 77658.22341776623, 100355.49822250842, 103289.0, 104264.0, 67041.66479167574, 68249.31750682529, 69516.4386849051, 80206.59896700479, 80808.59595701983, 45613.315800263685, 72376.63811680907, 74098.62950685211, 75305.62347188339, 76739.0, 8387.161283871565, 8548.187922147636, 8688.782780430287, 8806.779830504289, 8880.73357799267, 43246.567534324255, 44449.0, 44521.777391114134, 45172.096558069316, 46271.30593041174, 56700.432995671166, 62248.68875655593, 65356.0, 65967.34032659612, 67789.0, 11442.828357574477, 11563.710907227387, 11636.243644163102, 11757.0, 12207.023438125107, 12212.816807748068, 12724.491020359452, 13228.470861165446, 13333.866661333263, 13917.02580819333, 11473.196876218919, 11754.647360578854, 11987.0, 12367.93816030914, 12378.0, 12086.637400877988, 12307.323097229739, 12429.440675169459, 12467.127301088836, 12476.251424914171, 11283.548658053585, 11478.824115595417, 11622.593209237637, 11999.520019199483, 12016.0, 9806.460644664612, 10167.796644067224, 10356.067953884187, 10405.583776649151, 10461.895381046092, 9286.489243091424, 9493.952530237304, 9832.55753491109, 10222.846657299997, 10380.792384152124], "value": 6482.764540596478, "unit": "score"}], "name": "dromaeo_css", "value": 6345.423066019343, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 116.0, 113.0, 112.0, 112.0, 113.0, 112.0, 113.0, 113.0, 112.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 127.0, 130.0, 170.0, 123.0, 123.0, 153.0, 123.0, 161.0, 122.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 161.0, 163.0, 162.0, 162.0, 163.0, 162.0, 162.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 114.0, 77.0, 76.0, 75.0, 75.0, 114.0, 76.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 113.0, 86.0, 85.0, 84.0, 114.0, 85.0, 85.0, 125.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 121.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 64.0, 64.0, 63.0, 63.0, 63.0, 62.0, 63.0, 63.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 65.0, 65.0, 64.0, 64.0, 64.0, 64.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 79.0, 87.0, 81.0, 78.0, 77.0, 79.0, 79.0, 79.0, 83.0], "value": 80.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 127.0, 129.0, 127.0, 138.0, 130.0, 128.0, 130.0, 206.0, 130.0], "value": 137.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 169.0, 170.0, 173.0, 174.0, 168.0, 169.0, 167.0, 168.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 66.0, 67.0, 67.0, 67.0, 67.0, 67.0, 66.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1530.9, "alertThreshold": 2.0}]} 16:37:53 INFO - Return code: 0 16:37:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:37:53 INFO - # TBPL SUCCESS # 16:37:53 INFO - Running post-action listener: _resource_record_post_action 16:37:53 INFO - ##### 16:37:53 INFO - ##### Finished run-tests step (success) 16:37:53 INFO - ##### 16:37:53 INFO - Running post-run listener: _resource_record_post_run 16:37:53 INFO - Total resource usage - Wall time: 1200s; CPU: 13.0%; Read bytes: 4096; Write bytes: 472408064; Read time: 16; Write time: 547344 16:37:53 INFO - TinderboxPrint: CPU usage
13.0% 16:37:53 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 16:37:53 INFO - TinderboxPrint: I/O write bytes / time
472,408,064 / 547,344 16:37:53 INFO - TinderboxPrint: CPU idle
8,334.6 (87.0%) 16:37:53 INFO - TinderboxPrint: CPU user
1,201.9 (12.5%) 16:37:53 INFO - TinderboxPrint: Swap in / out
0 / 0 16:37:53 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 193933312; Read time: 0; Write time: 272124 16:37:53 INFO - run-tests - Wall time: 1184s; CPU: 13.0%; Read bytes: 0; Write bytes: 278474752; Read time: 0; Write time: 275220 16:37:54 INFO - Running post-run listener: _upload_blobber_files 16:37:54 INFO - Blob upload gear active. 16:37:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:37:54 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:37:54 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:37:54 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:37:54 INFO - (blobuploader) - INFO - Open directory for files ... 16:37:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:37:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:37:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:55 INFO - (blobuploader) - INFO - Done attempting. 16:37:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:37:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:37:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:59 INFO - (blobuploader) - INFO - Done attempting. 16:37:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:37:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:00 INFO - (blobuploader) - INFO - Done attempting. 16:38:00 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:00 INFO - Return code: 0 16:38:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7f87e599ff0dc1b87776778ed4cf13ad53572533e92d8a99533d07ce0c0fd288d143e798cafee6010c0ed1028dfe2e72df9bb8fbbcbcae9744bfbda8f519fe1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb734fa3ea41272de54646075502d61fe684e2f8699a34f5c62a3c233ed3ddf8328add1ae0c530ca9e4264bc29f8a1ee528b0992944c2dad7ef8fc5ad913231", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71d67938c0596839bb3c9d6226023a4ef359bdc1148007e41d3c28b106277fe3bef99067f0f77152323c5048c26dd1dac58c6b2a3f93c0e69ce40b0f3f68ff13"} 16:38:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:00 INFO - Contents: 16:38:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7f87e599ff0dc1b87776778ed4cf13ad53572533e92d8a99533d07ce0c0fd288d143e798cafee6010c0ed1028dfe2e72df9bb8fbbcbcae9744bfbda8f519fe1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb734fa3ea41272de54646075502d61fe684e2f8699a34f5c62a3c233ed3ddf8328add1ae0c530ca9e4264bc29f8a1ee528b0992944c2dad7ef8fc5ad913231", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71d67938c0596839bb3c9d6226023a4ef359bdc1148007e41d3c28b106277fe3bef99067f0f77152323c5048c26dd1dac58c6b2a3f93c0e69ce40b0f3f68ff13"} 16:38:00 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:00 INFO - Copying logs to upload dir... 16:38:00 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1312.631608 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 52 secs) (at 2016-07-08 16:38:00.041124) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 16:38:00.044414) ========= 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=920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277 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/7f87e599ff0dc1b87776778ed4cf13ad53572533e92d8a99533d07ce0c0fd288d143e798cafee6010c0ed1028dfe2e72df9bb8fbbcbcae9744bfbda8f519fe1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb734fa3ea41272de54646075502d61fe684e2f8699a34f5c62a3c233ed3ddf8328add1ae0c530ca9e4264bc29f8a1ee528b0992944c2dad7ef8fc5ad913231", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71d67938c0596839bb3c9d6226023a4ef359bdc1148007e41d3c28b106277fe3bef99067f0f77152323c5048c26dd1dac58c6b2a3f93c0e69ce40b0f3f68ff13"} build_url:https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/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/7f87e599ff0dc1b87776778ed4cf13ad53572533e92d8a99533d07ce0c0fd288d143e798cafee6010c0ed1028dfe2e72df9bb8fbbcbcae9744bfbda8f519fe1b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/aeb734fa3ea41272de54646075502d61fe684e2f8699a34f5c62a3c233ed3ddf8328add1ae0c530ca9e4264bc29f8a1ee528b0992944c2dad7ef8fc5ad913231", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/71d67938c0596839bb3c9d6226023a4ef359bdc1148007e41d3c28b106277fe3bef99067f0f77152323c5048c26dd1dac58c6b2a3f93c0e69ce40b0f3f68ff13"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 16:38:00.069237) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:38:00.069605) ========= 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=920470bf432669ad7e3a5387000001ca-1468018858.990194-96525277 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004449 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:38:00.153944) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 16:38:00.154264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 16:38:00.154642) ========= ========= Total master_lag: 0.16 =========