builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-057 starttime: 1468019766.72 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.722082) ========= 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.722435) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.722699) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.757666) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.757979) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-08 16:16:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.1M=0s 2016-07-08 16:16:07 (46.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.070595 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.843251) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.843522) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023152 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.892329) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:06.892625) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-08 16:16:07,947 truncating revision to first 12 chars 2016-07-08 16:16:07,947 Setting DEBUG logging. 2016-07-08 16:16:07,947 attempt 1/10 2016-07-08 16:16:07,947 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:08,264 unpacking tar archive at: mozilla-central-fd8ff97bc294/testing/mozharness/ program finished with exit code 0 elapsedTime=0.687208 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.592526) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.592796) ========= script_repo_revision: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.593159) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.593422) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 16:16:07.600936) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-08 16:16:07.601237) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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=934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:16:08 INFO - MultiFileLogger online at 20160708 16:16:08 in /builds/slave/test 16:16:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:08 INFO - {'append_to_log': False, 16:16:08 INFO - 'base_work_dir': '/builds/slave/test', 16:16:08 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 16:16:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:08 INFO - 'branch': 'Firefox-Non-PGO', 16:16:08 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:08 INFO - 'config_files': ('talos/linux_config.py',), 16:16:08 INFO - 'default_actions': ('clobber', 16:16:08 INFO - 'read-buildbot-config', 16:16:08 INFO - 'download-and-extract', 16:16:08 INFO - 'populate-webroot', 16:16:08 INFO - 'create-virtualenv', 16:16:08 INFO - 'install', 16:16:08 INFO - 'run-tests'), 16:16:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:08 INFO - 'download_minidump_stackwalk': True, 16:16:08 INFO - 'download_symbols': 'ondemand', 16:16:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:08 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:08 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:08 INFO - 'installer_path': 'installer.exe', 16:16:08 INFO - 'log_level': 'info', 16:16:08 INFO - 'log_name': 'talos', 16:16:08 INFO - 'log_to_console': True, 16:16:08 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:16:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:16:08 INFO - 'opt_config_files': (), 16:16:08 INFO - 'pip_index': False, 16:16:08 INFO - 'sps_profile': False, 16:16:08 INFO - 'sps_profile_interval': 0, 16:16:08 INFO - 'suite': 'dromaeojs-e10s', 16:16:08 INFO - 'system_bits': '32', 16:16:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:08 INFO - 'title': 'talos-linux64-ix-057', 16:16:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:08 INFO - 'use_talos_json': True, 16:16:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:08 INFO - 'work_dir': 'build'} 16:16:08 INFO - ##### 16:16:08 INFO - ##### Running clobber 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: clobber 16:16:08 INFO - rmtree: /builds/slave/test/build 16:16:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:09 INFO - Running post-action listener: _resource_record_post_action 16:16:09 INFO - ##### 16:16:09 INFO - ##### Finished clobber step (success) 16:16:09 INFO - ##### 16:16:09 INFO - ##### 16:16:09 INFO - ##### Running read-buildbot-config step. 16:16:09 INFO - ##### 16:16:09 INFO - Running pre-action listener: _resource_record_pre_action 16:16:09 INFO - Running main action method: read_buildbot_config 16:16:09 INFO - Using buildbot properties: 16:16:09 INFO - { 16:16:09 INFO - "project": "", 16:16:09 INFO - "product": "firefox", 16:16:09 INFO - "script_repo_revision": "production", 16:16:09 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 16:16:09 INFO - "repository": "", 16:16:09 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 16:16:09 INFO - "buildid": "20160708145625", 16:16:09 INFO - "pgo_build": "False", 16:16:09 INFO - "basedir": "/builds/slave/test", 16:16:09 INFO - "buildnumber": 394, 16:16:09 INFO - "slavename": "talos-linux64-ix-057", 16:16:09 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:16:09 INFO - "platform": "ubuntu64_hw", 16:16:09 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 16:16:09 INFO - "branch": "mozilla-central", 16:16:09 INFO - "repo_path": "mozilla-central", 16:16:09 INFO - "revision": "fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa", 16:16:09 INFO - "stage_platform": "linux64", 16:16:09 INFO - "builduid": "ad25074bc14c4d3db4cd5de075d55ec6", 16:16:09 INFO - "slavebuilddir": "test" 16:16:09 INFO - } 16:16:09 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:09 INFO - Running post-action listener: _resource_record_post_action 16:16:09 INFO - ##### 16:16:09 INFO - ##### Finished read-buildbot-config step (success) 16:16:09 INFO - ##### 16:16:09 INFO - ##### 16:16:09 INFO - ##### Running download-and-extract step. 16:16:09 INFO - ##### 16:16:09 INFO - Running pre-action listener: _resource_record_pre_action 16:16:09 INFO - Running main action method: download_and_extract 16:16:09 INFO - mkdir: /builds/slave/test/build/tests 16:16:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:09 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:09 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:09 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:09 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:09 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:15 INFO - Downloaded 1361 bytes. 16:16:15 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:16:15 INFO - Using the following test package requirements: 16:16:15 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:16:15 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:16:15 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:16:15 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'jsshell-linux-x86_64.zip'], 16:16:15 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:16:15 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:16:15 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:16:15 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:16:15 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:16:15 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:16:15 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:16:15 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 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:17 INFO - Downloaded 22735479 bytes. 16:16:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:16:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:16:18 INFO - Return code: 0 16:16:18 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:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:18 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:18 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:18 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:18 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:18 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:28 INFO - Downloaded 22735479 bytes. 16:16:28 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:28 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:29 INFO - Return code: 0 16:16: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:16:29 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:16: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.talos.tests.zip 16:16: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.talos.tests.zip 16:16: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.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:16:29 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:16:31 INFO - Downloaded 14403333 bytes. 16:16:31 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:16:31 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:16:31 INFO - Return code: 0 16:16:31 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:31 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:16:31 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:16:31 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:16:31 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:16:31 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:16:33 INFO - Downloaded 56647594 bytes. 16:16:33 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:16:33 INFO - mkdir: /builds/slave/test/properties 16:16:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:33 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:33 INFO - Contents: 16:16:33 INFO - build_url:https://queue.taskcluster.net/v1/task/fY4BBG0ISW6W4dbzqj4buQ/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:16:34 INFO - Running post-action listener: _resource_record_post_action 16:16:34 INFO - Running post-action listener: set_extra_try_arguments 16:16:34 INFO - ##### 16:16:34 INFO - ##### Finished download-and-extract step (success) 16:16:34 INFO - ##### 16:16:34 INFO - ##### 16:16:34 INFO - ##### Running populate-webroot step. 16:16:34 INFO - ##### 16:16:34 INFO - Running pre-action listener: _resource_record_pre_action 16:16:34 INFO - Running main action method: populate_webroot 16:16:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:34 INFO - '--tppagecycles', 16:16:34 INFO - '7'], 16:16:34 INFO - 'tests': ['tsvgm']}}, 16:16:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['damp', 'tps']}, 16:16:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'tests': ['damp', 'tps']}, 16:16:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['dromaeo_dom']}, 16:16:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['basic_compositor_video']}, 16:16:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:16:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['a11yr', 16:16:34 INFO - 'ts_paint', 16:16:34 INFO - 'tpaint', 16:16:34 INFO - 'sessionrestore', 16:16:34 INFO - 'sessionrestore_no_auto_restore', 16:16:34 INFO - 'tabpaint']}, 16:16:34 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:34 INFO - 'ts_paint', 16:16:34 INFO - 'tpaint', 16:16:34 INFO - 'sessionrestore', 16:16:34 INFO - 'sessionrestore_no_auto_restore', 16:16:34 INFO - 'tabpaint']}, 16:16:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:34 INFO - 'ts_paint', 16:16:34 INFO - 'tpaint', 16:16:34 INFO - 'sessionrestore', 16:16:34 INFO - 'sessionrestore_no_auto_restore', 16:16:34 INFO - 'tabpaint']}, 16:16:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:34 INFO - 'ts_paint', 16:16:34 INFO - 'tpaint', 16:16:34 INFO - 'sessionrestore', 16:16:34 INFO - 'sessionrestore_no_auto_restore', 16:16:34 INFO - 'tabpaint']}, 16:16:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['a11yr', 16:16:34 INFO - 'ts_paint', 16:16:34 INFO - 'tpaint', 16:16:34 INFO - 'sessionrestore', 16:16:34 INFO - 'sessionrestore_no_auto_restore', 16:16:34 INFO - 'tabpaint']}, 16:16:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['a11yr', 16:16:34 INFO - 'ts_paint', 16:16:34 INFO - 'tpaint', 16:16:34 INFO - 'sessionrestore', 16:16:34 INFO - 'sessionrestore_no_auto_restore', 16:16:34 INFO - 'tabpaint']}, 16:16:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['tsvgx', 16:16:34 INFO - 'tsvgr_opacity', 16:16:34 INFO - 'tart', 16:16:34 INFO - 'tscrollx', 16:16:34 INFO - 'cart']}, 16:16:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:34 INFO - 'tsvgr_opacity', 16:16:34 INFO - 'tart', 16:16:34 INFO - 'tscrollx', 16:16:34 INFO - 'cart']}, 16:16:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'talos_options': ['--disable-e10s'], 16:16:34 INFO - 'tests': ['tp5o']}, 16:16:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'tests': ['tp5o']}, 16:16:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'talos_options': ['--disable-e10s', 16:16:34 INFO - '--xperf_path', 16:16:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:34 INFO - 'tests': ['tp5n']}, 16:16:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:34 INFO - 'talos_options': ['--xperf_path', 16:16:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:34 INFO - 'tests': ['tp5n']}}, 16:16:34 INFO - 'talos.zip': {'path': '', 16:16:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:34 INFO - Running post-action listener: _resource_record_post_action 16:16:34 INFO - ##### 16:16:34 INFO - ##### Finished populate-webroot step (success) 16:16:34 INFO - ##### 16:16:34 INFO - ##### 16:16:34 INFO - ##### Running create-virtualenv step. 16:16:34 INFO - ##### 16:16:34 INFO - Running pre-action listener: _resource_record_pre_action 16:16:34 INFO - Running main action method: create_virtualenv 16:16:34 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:34 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:16:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:34 INFO - Using real prefix '/usr' 16:16:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:34 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:36 INFO - Installing pip.................done. 16:16:36 INFO - Return code: 0 16:16:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:36 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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16:36 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:16:36 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:16:36 INFO - Using env: {'DISPLAY': ':0', 16:16:36 INFO - 'HOME': '/home/cltbld', 16:16:36 INFO - 'LANG': 'en_US.UTF-8', 16:16:36 INFO - 'LANGUAGE': 'en_US:en', 16:16:36 INFO - 'LOGNAME': 'cltbld', 16:16:36 INFO - 'MAIL': '/var/mail/cltbld', 16:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:36 INFO - 'MOZ_NO_REMOTE': '1', 16:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:36 INFO - 'NO_EM_RESTART': '1', 16:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:36 INFO - 'PWD': '/builds/slave/test', 16:16:36 INFO - 'SHELL': '/bin/bash', 16:16:36 INFO - 'SHLVL': '1', 16:16:36 INFO - 'TERM': 'linux', 16:16:36 INFO - 'TMOUT': '86400', 16:16:36 INFO - 'USER': 'cltbld', 16:16:36 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:36 INFO - '_': '/tools/buildbot/bin/python'} 16:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:37 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:16:37 INFO - Cleaning up... 16:16:37 INFO - Return code: 0 16:16:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16: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:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', '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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:16: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 psutil>=3.1.1 16:16:37 INFO - Using env: {'DISPLAY': ':0', 16:16:37 INFO - 'HOME': '/home/cltbld', 16:16:37 INFO - 'LANG': 'en_US.UTF-8', 16:16:37 INFO - 'LANGUAGE': 'en_US:en', 16:16:37 INFO - 'LOGNAME': 'cltbld', 16:16:37 INFO - 'MAIL': '/var/mail/cltbld', 16:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:37 INFO - 'MOZ_NO_REMOTE': '1', 16:16:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:37 INFO - 'NO_EM_RESTART': '1', 16:16:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:37 INFO - 'PWD': '/builds/slave/test', 16:16:37 INFO - 'SHELL': '/bin/bash', 16:16:37 INFO - 'SHLVL': '1', 16:16:37 INFO - 'TERM': 'linux', 16:16:37 INFO - 'TMOUT': '86400', 16:16:37 INFO - 'USER': 'cltbld', 16:16:37 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:37 INFO - '_': '/tools/buildbot/bin/python'} 16:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:37 INFO - Downloading/unpacking psutil>=3.1.1 16:16: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:16: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:16: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:16: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:16: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:16: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:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:39 INFO - Installing collected packages: psutil 16:16:39 INFO - Running setup.py install for psutil 16:16:39 INFO - building 'psutil._psutil_linux' extension 16:16:39 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:16:39 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:16:39 INFO - building 'psutil._psutil_posix' extension 16:16:39 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:16:39 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:16:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:39 INFO - Successfully installed psutil 16:16:39 INFO - Cleaning up... 16:16:39 INFO - Return code: 0 16:16:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16: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:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:16: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 mozsystemmonitor==0.3 16:16:39 INFO - Using env: {'DISPLAY': ':0', 16:16:39 INFO - 'HOME': '/home/cltbld', 16:16:39 INFO - 'LANG': 'en_US.UTF-8', 16:16:39 INFO - 'LANGUAGE': 'en_US:en', 16:16:39 INFO - 'LOGNAME': 'cltbld', 16:16:39 INFO - 'MAIL': '/var/mail/cltbld', 16:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:39 INFO - 'MOZ_NO_REMOTE': '1', 16:16:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:39 INFO - 'NO_EM_RESTART': '1', 16:16:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:39 INFO - 'PWD': '/builds/slave/test', 16:16:39 INFO - 'SHELL': '/bin/bash', 16:16:39 INFO - 'SHLVL': '1', 16:16:39 INFO - 'TERM': 'linux', 16:16:39 INFO - 'TMOUT': '86400', 16:16:39 INFO - 'USER': 'cltbld', 16:16:39 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:39 INFO - '_': '/tools/buildbot/bin/python'} 16:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:16: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:16: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:16: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:16: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:16: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:16: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:16:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:16:42 INFO - Installing collected packages: mozsystemmonitor 16:16:42 INFO - Running setup.py install for mozsystemmonitor 16:16:42 INFO - Successfully installed mozsystemmonitor 16:16:42 INFO - Cleaning up... 16:16:42 INFO - Return code: 0 16:16:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:16: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:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', '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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:16: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 jsonschema==2.5.1 16:16:42 INFO - Using env: {'DISPLAY': ':0', 16:16:42 INFO - 'HOME': '/home/cltbld', 16:16:42 INFO - 'LANG': 'en_US.UTF-8', 16:16:42 INFO - 'LANGUAGE': 'en_US:en', 16:16:42 INFO - 'LOGNAME': 'cltbld', 16:16:42 INFO - 'MAIL': '/var/mail/cltbld', 16:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:42 INFO - 'MOZ_NO_REMOTE': '1', 16:16:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:42 INFO - 'NO_EM_RESTART': '1', 16:16:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:42 INFO - 'PWD': '/builds/slave/test', 16:16:42 INFO - 'SHELL': '/bin/bash', 16:16:42 INFO - 'SHLVL': '1', 16:16:42 INFO - 'TERM': 'linux', 16:16:42 INFO - 'TMOUT': '86400', 16:16:42 INFO - 'USER': 'cltbld', 16:16:42 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:42 INFO - '_': '/tools/buildbot/bin/python'} 16:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:42 INFO - Downloading/unpacking jsonschema==2.5.1 16:16: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:16: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:16: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:16: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:16: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:16: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:16:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:16: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:16: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:16: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:16: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:16: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:16: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:16:44 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:44 INFO - warning: no files found matching '*.txt' 16:16:44 INFO - no previously-included directories found matching 'build' 16:16:44 INFO - no previously-included directories found matching 'dist' 16:16:44 INFO - no previously-included directories found matching '.git*' 16:16:44 INFO - Installing collected packages: jsonschema, functools32 16:16:44 INFO - Running setup.py install for functools32 16:16:44 INFO - warning: no files found matching '*.txt' 16:16:44 INFO - no previously-included directories found matching 'build' 16:16:44 INFO - no previously-included directories found matching 'dist' 16:16:44 INFO - no previously-included directories found matching '.git*' 16:16:44 INFO - Successfully installed jsonschema functools32 16:16:44 INFO - Cleaning up... 16:16:44 INFO - Return code: 0 16:16:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16:44 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:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:44 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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16:44 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:16:44 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:16:44 INFO - Using env: {'DISPLAY': ':0', 16:16:44 INFO - 'HOME': '/home/cltbld', 16:16:44 INFO - 'LANG': 'en_US.UTF-8', 16:16:44 INFO - 'LANGUAGE': 'en_US:en', 16:16:44 INFO - 'LOGNAME': 'cltbld', 16:16:44 INFO - 'MAIL': '/var/mail/cltbld', 16:16:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:44 INFO - 'MOZ_NO_REMOTE': '1', 16:16:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:44 INFO - 'NO_EM_RESTART': '1', 16:16:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:44 INFO - 'PWD': '/builds/slave/test', 16:16:44 INFO - 'SHELL': '/bin/bash', 16:16:44 INFO - 'SHLVL': '1', 16:16:44 INFO - 'TERM': 'linux', 16:16:44 INFO - 'TMOUT': '86400', 16:16:44 INFO - 'USER': 'cltbld', 16:16:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:44 INFO - '_': '/tools/buildbot/bin/python'} 16:16:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:16: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:16: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:16: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:16: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:16: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:16: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:16:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16:47 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:16:47 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:16:47 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:16:47 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:16:47 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:16:47 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:16:47 INFO - Downloading docopt-0.6.1.tar.gz 16:16:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:47 INFO - Running setup.py install for blobuploader 16:16:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:47 INFO - Running setup.py install for requests 16:16:48 INFO - Running setup.py install for docopt 16:16:48 INFO - Successfully installed blobuploader requests docopt 16:16:48 INFO - Cleaning up... 16:16:48 INFO - Return code: 0 16:16:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:48 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:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:48 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:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:48 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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16:48 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:16:48 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:16:48 INFO - Using env: {'DISPLAY': ':0', 16:16:48 INFO - 'HOME': '/home/cltbld', 16:16:48 INFO - 'LANG': 'en_US.UTF-8', 16:16:48 INFO - 'LANGUAGE': 'en_US:en', 16:16:48 INFO - 'LOGNAME': 'cltbld', 16:16:48 INFO - 'MAIL': '/var/mail/cltbld', 16:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:48 INFO - 'MOZ_NO_REMOTE': '1', 16:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:48 INFO - 'NO_EM_RESTART': '1', 16:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:48 INFO - 'PWD': '/builds/slave/test', 16:16:48 INFO - 'SHELL': '/bin/bash', 16:16:48 INFO - 'SHLVL': '1', 16:16:48 INFO - 'TERM': 'linux', 16:16:48 INFO - 'TMOUT': '86400', 16:16:48 INFO - 'USER': 'cltbld', 16:16:48 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:48 INFO - '_': '/tools/buildbot/bin/python'} 16:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:48 INFO - Running setup.py (path:/tmp/pip-dSd8ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:48 INFO - Running setup.py (path:/tmp/pip-Ng0_qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:48 INFO - Running setup.py (path:/tmp/pip-eGjxQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:48 INFO - Running setup.py (path:/tmp/pip-b0T5j4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:48 INFO - Running setup.py (path:/tmp/pip-v9cDNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:48 INFO - Running setup.py (path:/tmp/pip-JI25da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:48 INFO - Running setup.py (path:/tmp/pip-f3ECHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:48 INFO - Running setup.py (path:/tmp/pip-cviCXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:49 INFO - Running setup.py (path:/tmp/pip-neNazN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:49 INFO - Running setup.py (path:/tmp/pip-clFg_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:49 INFO - Running setup.py (path:/tmp/pip-JrDRFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:49 INFO - Running setup.py (path:/tmp/pip-gTHVhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:49 INFO - Running setup.py (path:/tmp/pip-iWDgWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:49 INFO - Running setup.py (path:/tmp/pip-SgHYkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:49 INFO - Running setup.py (path:/tmp/pip-ZWXa1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:49 INFO - Running setup.py (path:/tmp/pip-hUs5l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:49 INFO - Running setup.py (path:/tmp/pip-WnSMz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:49 INFO - Running setup.py install for manifestparser 16:16:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:49 INFO - Running setup.py install for mozcrash 16:16:49 INFO - Running setup.py install for mozdebug 16:16:49 INFO - Running setup.py install for mozdevice 16:16:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for mozfile 16:16:50 INFO - Running setup.py install for mozhttpd 16:16:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for mozinfo 16:16:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for mozInstall 16:16:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for mozleak 16:16:50 INFO - Running setup.py install for mozlog 16:16:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for moznetwork 16:16:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for mozprocess 16:16:50 INFO - Running setup.py install for mozprofile 16:16:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:50 INFO - Running setup.py install for mozrunner 16:16:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:51 INFO - Running setup.py install for mozscreenshot 16:16:51 INFO - Running setup.py install for moztest 16:16:51 INFO - Running setup.py install for mozversion 16:16:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:51 INFO - Cleaning up... 16:16:51 INFO - Return code: 0 16:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:51 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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16:51 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:16:51 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:16:51 INFO - Using env: {'DISPLAY': ':0', 16:16:51 INFO - 'HOME': '/home/cltbld', 16:16:51 INFO - 'LANG': 'en_US.UTF-8', 16:16:51 INFO - 'LANGUAGE': 'en_US:en', 16:16:51 INFO - 'LOGNAME': 'cltbld', 16:16:51 INFO - 'MAIL': '/var/mail/cltbld', 16:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:51 INFO - 'MOZ_NO_REMOTE': '1', 16:16:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:51 INFO - 'NO_EM_RESTART': '1', 16:16:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:51 INFO - 'PWD': '/builds/slave/test', 16:16:51 INFO - 'SHELL': '/bin/bash', 16:16:51 INFO - 'SHLVL': '1', 16:16:51 INFO - 'TERM': 'linux', 16:16:51 INFO - 'TMOUT': '86400', 16:16:51 INFO - 'USER': 'cltbld', 16:16:51 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:51 INFO - '_': '/tools/buildbot/bin/python'} 16:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:51 INFO - Running setup.py (path:/tmp/pip-t6E2Oy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:51 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:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:51 INFO - Running setup.py (path:/tmp/pip-Te2Jmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:51 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:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:51 INFO - Running setup.py (path:/tmp/pip-Ahdxd5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:51 INFO - Running setup.py (path:/tmp/pip-xcmbX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:51 INFO - Running setup.py (path:/tmp/pip-A9IIS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:52 INFO - Running setup.py (path:/tmp/pip-YnaSfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:52 INFO - Running setup.py (path:/tmp/pip-zVtPWL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:52 INFO - Running setup.py (path:/tmp/pip-CY9EBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:52 INFO - Running setup.py (path:/tmp/pip-7V9QrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:52 INFO - Running setup.py (path:/tmp/pip-QccvJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:52 INFO - Running setup.py (path:/tmp/pip-CuVJAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:52 INFO - Running setup.py (path:/tmp/pip-Grvagw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:52 INFO - Running setup.py (path:/tmp/pip-PLGycJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:52 INFO - Running setup.py (path:/tmp/pip-Eww9sb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:52 INFO - Running setup.py (path:/tmp/pip-4iFQGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:52 INFO - Running setup.py (path:/tmp/pip-1EHlAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:52 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:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:52 INFO - Running setup.py (path:/tmp/pip-uhMoHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:54 INFO - Downloading blessings-1.6.tar.gz 16:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:16:54 INFO - Installing collected packages: blessings 16:16:54 INFO - Running setup.py install for blessings 16:16:54 INFO - Successfully installed blessings 16:16:54 INFO - Cleaning up... 16:16:55 INFO - Return code: 0 16:16:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:55 INFO - Reading from file tmpfile_stdout 16:16:55 INFO - Current package versions: 16:16:55 INFO - argparse == 1.2.1 16:16:55 INFO - blessings == 1.6 16:16:55 INFO - blobuploader == 1.2.4 16:16:55 INFO - docopt == 0.6.1 16:16:55 INFO - functools32 == 3.2.3-2 16:16:55 INFO - jsonschema == 2.5.1 16:16:55 INFO - manifestparser == 1.1 16:16:55 INFO - mozInstall == 1.12 16:16:55 INFO - mozcrash == 0.17 16:16:55 INFO - mozdebug == 0.1 16:16:55 INFO - mozdevice == 0.48 16:16:55 INFO - mozfile == 1.2 16:16:55 INFO - mozhttpd == 0.7 16:16:55 INFO - mozinfo == 0.9 16:16:55 INFO - mozleak == 0.1 16:16:55 INFO - mozlog == 3.2 16:16:55 INFO - moznetwork == 0.27 16:16:55 INFO - mozprocess == 0.23 16:16:55 INFO - mozprofile == 0.28 16:16:55 INFO - mozrunner == 6.12 16:16:55 INFO - mozscreenshot == 0.1 16:16:55 INFO - mozsystemmonitor == 0.3 16:16:55 INFO - moztest == 0.7 16:16:55 INFO - mozversion == 1.4 16:16:55 INFO - psutil == 3.1.1 16:16:55 INFO - requests == 1.2.3 16:16:55 INFO - wsgiref == 0.1.2 16:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:55 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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16:55 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:16:55 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:16:55 INFO - Using env: {'DISPLAY': ':0', 16:16:55 INFO - 'HOME': '/home/cltbld', 16:16:55 INFO - 'LANG': 'en_US.UTF-8', 16:16:55 INFO - 'LANGUAGE': 'en_US:en', 16:16:55 INFO - 'LOGNAME': 'cltbld', 16:16:55 INFO - 'MAIL': '/var/mail/cltbld', 16:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:55 INFO - 'MOZ_NO_REMOTE': '1', 16:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:55 INFO - 'NO_EM_RESTART': '1', 16:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:55 INFO - 'PWD': '/builds/slave/test', 16:16:55 INFO - 'SHELL': '/bin/bash', 16:16:55 INFO - 'SHLVL': '1', 16:16:55 INFO - 'TERM': 'linux', 16:16:55 INFO - 'TMOUT': '86400', 16:16:55 INFO - 'USER': 'cltbld', 16:16:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:55 INFO - '_': '/tools/buildbot/bin/python'} 16:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 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:16:55 INFO - Cleaning up... 16:16:55 INFO - Return code: 0 16:16:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:55 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 0x1b62030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1cc58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d91400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, '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': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', '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:16:55 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:16:55 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:16:55 INFO - Using env: {'DISPLAY': ':0', 16:16:55 INFO - 'HOME': '/home/cltbld', 16:16:55 INFO - 'LANG': 'en_US.UTF-8', 16:16:55 INFO - 'LANGUAGE': 'en_US:en', 16:16:55 INFO - 'LOGNAME': 'cltbld', 16:16:55 INFO - 'MAIL': '/var/mail/cltbld', 16:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:55 INFO - 'MOZ_NO_REMOTE': '1', 16:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:55 INFO - 'NO_EM_RESTART': '1', 16:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:55 INFO - 'PWD': '/builds/slave/test', 16:16:55 INFO - 'SHELL': '/bin/bash', 16:16:55 INFO - 'SHLVL': '1', 16:16:55 INFO - 'TERM': 'linux', 16:16:55 INFO - 'TMOUT': '86400', 16:16:55 INFO - 'USER': 'cltbld', 16:16:55 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:55 INFO - '_': '/tools/buildbot/bin/python'} 16:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:16:55 INFO - Cleaning up... 16:16:55 INFO - Return code: 0 16:16:55 INFO - Running post-action listener: _resource_record_post_action 16:16:55 INFO - Running post-action listener: _start_resource_monitoring 16:16:55 INFO - Starting resource monitoring. 16:16:55 INFO - ##### 16:16:55 INFO - ##### Finished create-virtualenv step (success) 16:16:55 INFO - ##### 16:16:55 INFO - ##### 16:16:55 INFO - ##### Running install step. 16:16:55 INFO - ##### 16:16:55 INFO - Running pre-action listener: _resource_record_pre_action 16:16:55 INFO - Running main action method: install 16:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:55 INFO - Reading from file tmpfile_stdout 16:16:55 INFO - Detecting whether we're running mozinstall >=1.0... 16:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:16:56 INFO - Reading from file tmpfile_stdout 16:16:56 INFO - Output received: 16:16:56 INFO - Usage: mozinstall [options] installer 16:16:56 INFO - Options: 16:16:56 INFO - -h, --help show this help message and exit 16:16:56 INFO - -d DEST, --destination=DEST 16:16:56 INFO - Directory to install application into. [default: 16:16:56 INFO - "/builds/slave/test"] 16:16:56 INFO - --app=APP Application being installed. [default: firefox] 16:16:56 INFO - mkdir: /builds/slave/test/build/application 16:16:56 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:16:56 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:17:12 INFO - Reading from file tmpfile_stdout 16:17:12 INFO - Output received: 16:17:12 INFO - /builds/slave/test/build/application/firefox/firefox 16:17:12 INFO - Running post-action listener: _resource_record_post_action 16:17:12 INFO - ##### 16:17:12 INFO - ##### Finished install step (success) 16:17:12 INFO - ##### 16:17:12 INFO - ##### 16:17:12 INFO - ##### Running run-tests step. 16:17:12 INFO - ##### 16:17:12 INFO - Running pre-action listener: _resource_record_pre_action 16:17:12 INFO - Running main action method: run_tests 16:17:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:17:12 INFO - Python 2.7.3 16:17:12 INFO - Return code: 0 16:17:12 INFO - grabbing minidump binary from tooltool 16:17: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:17:12 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 0x1e20570>, '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 0x1e21470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1e21900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:17:12 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:17:12 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:17:12 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:17:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:17:12 INFO - Return code: 0 16:17:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:17:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:17:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:17:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:17:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:17:12 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-e10s', '--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-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:17:12 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-e10s --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-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:17:12 INFO - Using env: {'DISPLAY': ':0', 16:17:12 INFO - 'HOME': '/home/cltbld', 16:17:12 INFO - 'LANG': 'en_US.UTF-8', 16:17:12 INFO - 'LANGUAGE': 'en_US:en', 16:17:12 INFO - 'LOGNAME': 'cltbld', 16:17:12 INFO - 'MAIL': '/var/mail/cltbld', 16:17:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:17:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:12 INFO - 'MOZ_NO_REMOTE': '1', 16:17:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:17:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:17:12 INFO - 'NO_EM_RESTART': '1', 16:17:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:12 INFO - 'PWD': '/builds/slave/test', 16:17:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:17:12 INFO - 'SHELL': '/bin/bash', 16:17:12 INFO - 'SHLVL': '1', 16:17:12 INFO - 'TERM': 'linux', 16:17:12 INFO - 'TMOUT': '86400', 16:17:12 INFO - 'USER': 'cltbld', 16:17:12 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389', 16:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:12 INFO - '_': '/tools/buildbot/bin/python'} 16:17:12 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-e10s', '--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-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:17:12 INFO - mozversion application_buildid: 20160708145625 16:17:12 INFO - mozversion application_changeset: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa 16:17:12 INFO - mozversion application_display_name: Nightly 16:17:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:17:12 INFO - mozversion application_name: Firefox 16:17:12 INFO - mozversion application_remotingname: firefox 16:17:12 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:17:12 INFO - mozversion application_vendor: Mozilla 16:17:12 INFO - mozversion application_version: 50.0a1 16:17:12 INFO - mozversion platform_buildid: 20160708145625 16:17:12 INFO - mozversion platform_changeset: fd8ff97bc294ec2d0f57408dae0c3b1e50ff62fa 16:17:12 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:17:12 INFO - mozversion platform_version: 50.0a1 16:17:12 INFO - using testdate: 1468019832 16:17:12 INFO - actual date: 1468019832 16:17:12 INFO - starting webserver on 'localhost:54240' 16:17:12 INFO - SUITE-START | Running 2 tests 16:17:12 INFO - TEST-START | dromaeo_css 16:17:12 INFO - operating with platform_type : linux_ 16:17:12 INFO - Initialising browser for dromaeo_css test... 16:17:12 INFO - TEST-INFO | started process 31360 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHFJc8P/profile http://localhost:54240/getInfo.html) 16:17:17 INFO - PROCESS | 31360 | __metrics Screen width/height:1600/1200 16:17:17 INFO - PROCESS | 31360 | colorDepth:24 16:17:17 INFO - PROCESS | 31360 | Browser inner width/height: 1024/697 16:17:17 INFO - PROCESS | 31360 | __metrics 16:17:18 ERROR - PROCESS | 31360 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2957: TypeError: this.tabs is undefined 16:17:19 INFO - TEST-INFO | 31360: exit 0 16:17:19 INFO - Browser initialized. 16:17:19 INFO - Running cycle 1/1 for dromaeo_css test... 16:17:19 INFO - TEST-INFO | started process 31477 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpHFJc8P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:31 INFO - PROCESS | 31477 | RSS: Main: 168988672 16:17:31 INFO - PROCESS | 31477 | 16:18:20 INFO - PROCESS | 31477 | 1468019900874 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:20:31 INFO - PROCESS | 31477 | Cycle 1(1): loaded http://localhost:54240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54240/tests/dromaeo/cssquery-ext.html) 16:20:33 INFO - PROCESS | 31477 | RSS: Main: 168845312 16:20:33 INFO - PROCESS | 31477 | 16:23:35 INFO - PROCESS | 31477 | Cycle 1(1): loaded http://localhost:54240/tests/dromaeo/cssquery-ext.html (next: http://localhost:54240/tests/dromaeo/cssquery-jquery.html) 16:23:35 INFO - PROCESS | 31477 | RSS: Main: 168550400 16:23:35 INFO - PROCESS | 31477 | 16:26:37 INFO - PROCESS | 31477 | Cycle 1(1): loaded http://localhost:54240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54240/tests/dromaeo/cssquery-mootools.html) 16:26:38 INFO - PROCESS | 31477 | RSS: Main: 171098112 16:26:38 INFO - PROCESS | 31477 | 16:29:40 INFO - PROCESS | 31477 | Cycle 1(1): loaded http://localhost:54240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54240/tests/dromaeo/cssquery-prototype.html) 16:29:41 INFO - PROCESS | 31477 | RSS: Main: 167292928 16:29:41 INFO - PROCESS | 31477 | 16:32:43 INFO - PROCESS | 31477 | Cycle 1(1): loaded http://localhost:54240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54240/tests/dromaeo/cssquery-yui.html) 16:32:44 INFO - PROCESS | 31477 | RSS: Main: 169668608 16:32:44 INFO - PROCESS | 31477 | 16:35:46 INFO - PROCESS | 31477 | Cycle 1(1): loaded http://localhost:54240/tests/dromaeo/cssquery-yui.html (next: http://localhost:54240/tests/dromaeo/cssquery-dojo.html) 16:35:47 INFO - PROCESS | 31477 | RSS: Main: 170852352 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | __start_tp_report 16:35:47 INFO - PROCESS | 31477 | _x_x_mozilla_page_load 16:35:47 INFO - PROCESS | 31477 | _x_x_mozilla_page_load_details 16:35:47 INFO - PROCESS | 31477 | |i|pagename|runs| 16:35:47 INFO - PROCESS | 31477 | |0;dojo.html;2570.627259047438;2603.6485074514935;2619.201143651186;2632.460345629146;2648.6556747622794;4806.495317991608;4855.077535268301;4867.975660121699;4893.094777466171;4898.632602554805;4635.907281854361;4685.672002959795;4708.22314318138;4761.69049011814;4765.475797662254;4382.868513944587;4441.2893936970095;4465.575770301816;4516.367708520794;4572.245579479382;4438.977805110986;4490.685652004361;4524.185646583614;4541.886452838672;4557.20248956433;174.36806389658454;4423.049044455457;4427.6015158635555;4454.35411865281;4510.706804057742;4472.105578884204;4476.462824461053;4485.529019452943;4498.167838949805;4515.05183911379;4432;4432.357308190294;4432.844850430251;4487.753173575452;4498.8875278117985;4741.2651039089;4823.03539292143;4845.4185497740145;4878.682885612424;4884.829030983934;5170.073458958808;5199.14214154664;5206.557442617382;5208.218767184915;5241.68549887008;4977.178765503688;5220.895582088375;5222.451642577551;5239.1093514102695;5245.291885595434;4761.190597598416;4766.52334766524;4803.759812009421;4805.063012712488;4809.2064809306075;5236.554892834151;5254.316938798008;5291.100512912737;5294.788208471618;5353.705546194997;4939.629527785431;5048.7475626219275;5060.367454068241;5068.113080211022;5069.188929771219;5160.045391602565;5204.141316682705;5219.634625576249;5226.5818734501145;5284.445133261029;5152.330197074356;5160.200168973815;5174.818881339135;5175.094358487249;5229.607779416559;4912.852614421573;4987.950120498748;5037.0681423936685;5122.231665250242;5124.666896651705;4329.761863097497;4343;4397.098594788061;4422.226110430662;4460.130274596422;3791.3933770597123;3816.065064059323;3852.5376954765047;3859.4982652255026;3917.196974620196;4301.118270754488;4405.427294451701;4435.800388982503;4442.48911375196;4462.866114016585;5237.6071794615555;5251.52736253739;5255.343082114736;5263.789448422097;5287.07476191665;4429.2027435062;4471.776411179428;4496.280595104768;4523.7964291606295;4527.275635898241;4478.843240486567;4498.685092043525;4521.480029796616;4544.500104988449;4658.34783130362;4691.507391723888;4726.574608285271;4728.196206644948;4739.170645137087;4750.928736068892;5049.293098966221;5056.57019153376;5061.670991385548;5092.592592592658;5140.177571588678;4707.317438971399;4734.26618874075;4770.7137571744415;4817.638677099161;4821.397325334333;5088;5090.872728181677;5098.694078355311;5113.948860511347;5154.201098829687;5068.062408454448;5172.043172013189;5174.042802081627;5178.197379406198;5190.117679994334;4951.5048495150195;4986.376702912136;4994.4755800640405;4997.100521905946;5016.347874776402;4996.025774973845;5005.549500544968;5006.824761133343;5008.3238762768015;5061.797528098835;4780.378550788514;4896.069746748107;4951.579115775199;4953.72754498506;5004.299398084198;4918.43438004634;5001.424836143745;5057.544820966131;5077.263796749525;5176.844694659167;4949.7030178189425;4963.503649635008;4969.47820478859;4983.053219888209;5025.547700706954 16:35:47 INFO - PROCESS | 31477 | |1;ext.html;549.1926867504769;556.6437480012792;570.7346084070906;579.5682216748523;586.8855573163235;33057.33885322292;33999.490007649874;37899.24201515968;38012;38147.80926095373;37669.058273543174;38385.42421863674;38471.038224044416;38546.22907541847;38563.807180964126;7486.663100160492;7752.3410510106705;7797.103333116679;7820.726274580391;7835.8432831343625;7672.961635191844;7774.2614451627005;7810.297073263405;7854.332381747558;7857.174996625357;7833.315837095023;7835.059792824841;7853.646585903648;7855.921440785604;7871.330936870373;7588.58262795546;7745.573993430359;7801.180876007994;7810.921890781133;7826.373890088779;7662.08055033394;7671.808204794925;7744.49660772048;7749.457537972344;7753.8449231015065;35291.4706279406;35669.82165089158;35858.28283434317;36012.63987360145;36075.458868117;2420.4674971506242;2741.3420779012895;3091.2271932016997;3127.53087036944;3214.9357012859846;27789.305267368276;28170;28272.717272827213;28495.145145645663;29061.564076538867;2777.8749956251813;2821.604975303459;2832.3485598312295;2861.2846788302923;2901.2601786544296;231224;235943;237032;238555;239133;28766.712332876403;29108.126756197347;29218.853905730335;29315.85342073276;32867.67132328694;33589;33835.66164338374;33899.66100338965;33992.83003584966;34116.829415852764;13728.382222799997;13741.587752367444;13750.106243094167;13756.449742010207;13898.444062237599;11707.2975621463;11725.530978760924;11745.88254117465;11758.05935525156;11936.820947685792;8005.639746211435;8017.919820801834;8054.073781515201;8068.99137607799;8086.959565202136;3102.565640810288;3110.5178697301953;3133.3576616793957;3172.4289627867206;3179.2528755742383;784.8430313937235;798.7883210949103;850.6257246811383;870.2820173357006;908.9681861134828;26952.73047269502;27310.863445682644;27995.860020699765;28408;28483.145505634864;894.1148263219496;906.4833045164193;914.8033172867732;938.2587755673078;943.5989704375613;14119.576412707635;14505.49230776939;14882.851171488146;15116.54650360491;15118.244087795567;397979;399190;399577;400162;401822;49652.50347496479;49654.503454964986;49984.75007624939;50208.49791502038;50376.74811625919;1120.1207052463776;1155.6821873984554;1156.9074474042225;1161.5469966712988;1162.9709257268466;26351;27166.592501112893;27668.86165569159;27972.860135699193;28124.156275311772;43415.34876976785;43523.347149793415;43827.561724383624;43882;44008.779956100014;343566;347038;347234;347680;347760;25689.201755876908;25713.742862571136;25833.48333033291;25928.870355648854;26117.216483505526;43249.567504325816;43673.78163109164;43969.560304397826;43998;44372.77813610911;35910;37258.205634576974;37682;37922.62077379191;38068.80965595154;36809.81595092007;37129.443058354685;37419.251614968096;37521;37930;34469;34819.82590087135;35022.47466288059;35136;35392.29215415626;32203.35593288168;32727;33488.497672534446;33669;33795;24839.60586013295;25031.550286684876;25214.873925630254;25453.61819572745;25648.358791030372 16:35:47 INFO - PROCESS | 31477 | |2;jquery.html;366.91194113412774;371.5096073183397;372.998135009325;382.7684251028126;385.68373933374625;16324.91837540812;17291.827081729178;17948.371806986757;18038.098095095258;18068.277268909227;15201.543953681406;16270.593235169126;16416.261268242928;16423.83576164238;17253.827461725377;4717.198076327024;4791.880202994918;4811.639127065467;4894.3392641993405;4902.068606964684;3512.3150985557827;4782.593479554242;5229.3201883755055;5460.808871689492;5528.336599608053;5560.526460487974;5572.164175373706;5577.3028371453565;5589.441055894398;5624.0439125348785;5464.508194262516;5569.554435645138;5573.108302671553;5579.972100139474;5579.995600791855;5150.896982060338;5337.70642614656;5345.278387417727;5349.037173308803;5360.678359298416;18943.62112757737;20370.49073773153;20419.79580204194;20617;21828.236011739515;1705.6673948580033;1921.4331772127257;2007.6787713965696;2372.5254949010096;2467.716212635534;9235.937867145298;9426.387284826533;9502.429854208769;9634.42193468387;9660.227181825438;1509.7810817431412;1573.1976691887105;1578.486991727693;1606.7991501062368;1633.4527933142413;66143.3385666142;69342;70346.99999999949;70464;70728;10166.796664066826;10213.387196768217;10354.275200735876;10401.115905147995;10525.789484210272;14171.92914035444;14194;14214.289285535682;14218.786718199235;14249.928750356183;6969.257685578606;7294.738631024294;7359.4848360615315;7450;7510.812229694301;6776.728930842711;6843.247242803288;6852.349026842442;6944.062551555577;6973.163220413583;4375.387445757597;4403.383526306319;4414.139242847678;4507.391502147234;4525.796339164745;1702.9659406811795;1734.2282684205659;1851.4445666300055;1884.3498992847447;1888.5373083594607;726.2664708644202;867.2195024477894;891.6121487153109;892.3262936482914;930.3998920696113;16559.840811143105;16596.17019149038;16631.91684041572;16861;17150.142492875308;805.7260531419346;820.8440396324798;828.1015098618067;888.3159966825508;901.4456109492614;8862.291016718647;9228.215601673799;9587.808243835085;9630.759231019281;9776.560054797552;377554;380575;383465;384270;384300;47400.28899566509;58639;62531.062034069524;76815.23184768192;78430;901.6393442623134;904.5391692416699;910.1990248581338;910.882198887589;914.5518695838991;14488.492902748354;15038.774418383515;15267.465638702592;15353;15514.689706206038;36222.63777362193;36897.63102368942;37377.4393384094;38262.04344891289;38366.616333836304;52482.212766809294;53864;53999.19001214906;54099;55772;6814;7086.362227399559;7167.247439019036;7360.926390736024;7821.804454888445;8782.736517904474;11301.378424186749;11331.943340283246;12326.445309961253;12565.246085234916;10087.646932357333;10175.643852465128;10184.439855808001;10303.227257955834;10424.374537527772;10591.841122383017;10981.3960232188;10989.780204396027;11045.392503412068;11124.777504450027;10148.644797432054;10376.533056012346;10464.953504649475;10510.737231569272;10715.571377144826;7586.393088553013;7661.502002369828;7793.506078054968;7884.172161922916;7899;9104.226140778108;9329.393589416666;9742.512874356113;9814.31299809035;10200.28597998133 16:35:47 INFO - PROCESS | 31477 | |3;mootools.html;1391.6173052410586;1421.239636794315;1531.1884701108415;1709.8119206887231;1732.2291580246788;6696.06255124283;7273.63631818409;7326.926730732691;7740.45816792824;7744.806379840506;7391.704331826721;7630.504017238875;7657.731979380723;7692.4615276930635;7703.229677032294;2165.415337858781;2191.6712493126;2204.526026904215;2208.403730992631;2210.4142402263415;2154.795294447033;2201.6037113319594;2205.40454077399;2218.378853920905;2221.489057516772;2200.9449763755874;2205.63607004844;2213.269621025058;2218.5784700906856;2222.5888210680996;2153.6446486329737;2159.136345461812;2161.2003558683386;2161.232762369352;2162.4810045589024;2041.4794227472048;2083.8437117216117;2093.8743675379374;2094.2565389286888;2100.6323893318763;7397.593132377717;7660.2722741339485;7682.884756728654;7690.7308244211745;7731.072271327421;4335.848245311399;4452;4457.086297309076;4457.24226881431;4471.0610771737975;2815.9014434494693;2829.575563665446;2851.458222937635;2872.540393537024;2874.6345485894244;855.482433127961;885.4642941020654;886.3972498700881;899.7615631857562;904.0913881549001;10272.84590731132;10292.536835842406;10311.587536498531;10312.587496500131;10336.0697537222;4013.1171142348635;4158.2307273154565;4223.070924396628;4272.060146767706;4318.071614602875;8829.16122968317;8881.42270752399;8894.421862579042;8933.776655583662;8945.865812012826;4482.394876691605;4503.954960450354;4510.323451482304;4550.88622784433;4551.294549344857;3471.8437670304897;3967.484227050465;4100.34394496879;4105.97947010263;4118.320477121241;3544.556930383702;3553.520274762874;3636.1819090454774;3676.9448458273146;3686.096906257984;1192.5527927027365;1212.1878341511208;1214.228964607463;1219.5000049979467;1226.1110694746237;692.5671455340412;742.6546655805076;811.3792948394483;836.9036562103664;843.1020962674731;5020.3724534433195;5087.923681144785;5088.465711100355;5137.845864624067;5169.767360468788;390.75187256092585;396.3025075866496;396.76987347338473;397.5388549282876;420.58992482329484;3462.567179102612;3586.1393265616066;3612.909677258037;3640.9817950910074;3663.8900832975055;8971.461712297283;9224.584893679801;9341;9343.439393636403;9361.110694484149;8610.741677749553;9247.537623118951;9288.628454861864;9293.860592091123;9299.907000929905;6584.8353791155605;6798.354156355138;6906.689198985957;6909.585424874423;6961.860762784716;5104.2088476286235;5113.233015047773;5204.271402003798;5261.710605916712;5321.494458026481;8290.58547072656;8295.460795048302;8307.210814972568;8364.247217750431;8389.077201507707;8218.547979860927;8438.071812100787;8570.314374850122;8678.82642347162;8685.348598854986;6347.39699728525;6924.826879327856;7100.112485939258;7141.321574450627;7312.1591017033725;7400.185979542353;7412.555246685216;7434.293742094493;7448.664810083667;7481.17707052213;6571.244306904576;6691.76578819739;6823.658817059227;6831.658417079226;6853.109095817711;6654.966725166537;6661.600303981777;6687.063811066358;6751.426128779109;6822.420094292041;5867.354590995073;6003.279606447254;6010.368911264254;6045.183900173421;6053.061775424816;4251.06476575153;4271.423357846776;4284.764337961442;4356.150550642595;4381.978090109529;4232.047789247395;4237.449131612871;4286.399904013503;4298.20483210607;4318.201132790444 16:35:47 INFO - PROCESS | 31477 | |4;prototype.html;278;278.164116828929;284.90882917466405;285.21850130642036;290.3771410324852;5121.257417674435;5126.3335766350365;5139.408967968685;5163.45783692712;5167.405748338942;5051.292819005343;5056.898862022757;5059.898802023958;5064.24036394541;5064.873378165547;3774.282886251617;3847.5190601174854;3934.665553427962;3941.645251927326;3959.6436320731127;3932.252871954334;3945.7829819360068;3968.2658708138943;3969.7618142911374;3972.245273398045;3923.8430462781453;3936.7047471439614;3939.3500072488005;3960.9207815843665;3972.36442169253;3778.9607857839037;3919.7844118573466;3934.626210509997;3943.861964831217;3946.112124771932;3423.911414037435;3435.845386957593;3440.896773096811;3445.552078229839;3445.6898879101;4945.282933974553;4963.131451995922;4967.776450059756;4968.6025117990475;4976.2038073908;4401.515833258339;4498.730076195438;4510.300903359985;4512.91689994399;4528.796204170821;5202.323697919284;5248.239005344242;5274.367075950872;5278.762455689464;5287.656302340336;3715.777053376806;3726.329260733067;3746.569144548357;3756.286305601927;3769.3968964965475;5397.70312632809;5553.8333849984565;5562.3881373048935;5572.832815015556;5592.5246354059955;3813.733038687321;3822.4075268332895;3830.3871380578976;3832.0301766130915;3834.5206849143856;3872.283627528916;3882.650561449483;3885.3977633466857;3891.8248678809523;3927.7054220932973;4488.842890498882;4497.347884556721;4506.233940230169;4511.187986162457;4520.299353600197;5230.483159883591;5281.419043905167;5353.625246232804;5367.704776237344;5378.596605254544;1343.4760443427072;1347.905646604748;1350.1763924006348;1351.486435154635;1352.0873410447907;829.6266679994027;846.8912408103777;854.5428195915156;862.6420035685246;877.7761670773912;4541.250693635579;4636.8145274188655;4644.396228490275;4650.67445278834;4678.7660616969015;5491.313585801775;5521.5306698931045;5524.309461317335;5540.529055030287;5605.663660180402;4443.755593442392;4682.682072374197;4695.906081878344;4698.271767875985;4717.811287548459;2913.504704200249;2951.6605590356953;2957.8521073946217;2965.599644048069;2968.762499000075;5115.309433226541;5142.382914050263;5159.355080614923;5162.6902385856965;5174.353205849269;5451.938305962004;5670.943290567042;5681.204631351614;5683.403242659461;5703.54371650267;5110.97444512775;5115.462876397924;5132.12753831842;5149.6910185389;5173.714691171133;5526.696031718133;5536.7785288588;5537.7784888604;5558.194061860927;5561.304836895388;5230.293910322058;5368.8389348319615;5401.810936617199;5402.378726446509;5424.864378390414;5093.592512599057;5103.515166059218;5115.314459908578;5123.769430375716;5170.948290517197;3447.3622379859808;3470;3481.408160612651;3502.4921386399337;3503.1242189452637;5004.524570165829;5248.685078895279;5559.52744016747;5567.498925096761;5627.352854421631;5397.487238712316;5402.135658294654;5411.458854114557;5431.619786614899;5437.755301011385;5197.896042079062;5394.703291318858;5419.918701219563;5423.972880135574;5484.753186106715;5291.894162116813;5302.231176479466;5311.9468805313;5314.070037743457;5342.866428339323;5141.2288156776785;5226.170840206049;5415.350157981067;5428.348598168087;5489.011977843867;5285.656432331886;5289.338832645919;5295.76169072385;5296.602754793329;5361.544268737201 16:35:47 INFO - PROCESS | 31477 | |5;yui.html;657.858560409512;661.8345413646589;665.0589415976393;738.0258059361645;751.5152726491411;12847.614571562857;13179.406926688298;13626.318684065807;13679.931600341997;13687.110337828035;13164.670883227924;13566.45734170632;13571.660708482294;13635.659108522266;13647.931760341184;626.2829060725467;757.4735558786632;760.1752098972621;773.4508498965713;782.4131901074194;792.5957761541626;795.3041089047084;797.1072398913225;799.3884678221166;799.4204201953595;835.5571547080058;836.6695155413609;837.3050368194384;837.623069618671;842.149429076634;827.028241815867;829.4857188543111;832.4297855968681;832.8917240758723;833.5248908122372;612.8161551534566;615.1818081950994;615.2340336281359;616.0512810272176;618.0944915698478;13039.413226404835;13104.08271421001;13186.340682965812;13196.406161722745;13245.602631921058;13630;14026.579202623938;14135.575932722035;14141.787873181911;14406.135631862016;9768.51157442125;9795.804083918352;9923.454210018446;10748.838767418494;10810.567577296899;4239.745615263063;4280.4221430106845;4289.549597292302;4296.591823776736;4421.447319085114;61293.6935315325;61810.38189618136;61849.690751546404;62437.68781156065;63089;10119.59521619127;10285.125764309967;10595.993380628828;10649.9467502662;10722.517486713117;17396.913015434842;17637.647247055243;17735.379261726037;17850.91074544619;18369.357072502396;6674.933250667431;6714.731410743516;6759.729610815512;6774.288699686561;6987.89518157228;3507.8421471033867;3650.7079433645245;3720.627937206312;3743.344914639927;3746.9063273418383;2698.6491756071587;2799.216219458555;2802.327441414087;2807.6771171315154;2807.7051909549614;479.09451137350436;485.46841208876225;494.1327969413656;499.37827404881153;501.77169387928404;464.5076219207651;516.9689818610896;570.8373113662586;597.5428796970322;603.2971588099815;7261.8547629047125;7485.214052524516;7788.532688038736;7900.3284720799375;8072.919270807334;492.308306828906;544.5697898660014;553.9058360078797;564.4045531963735;583.1660725163058;3561.715062795022;3780.2250538639514;3794.7533410328792;3891.2606604745006;4035.2938235808615;90530;91723.54138229266;92182.53908730413;92580;92800;81626.59186704147;82827.58586207031;83869.58065209757;83897.58051209705;84520;69534.65232673804;70846.64576677083;71663.64168179125;72016.91974620384;73304.63347683227;7168.53404528704;7266.818329541804;7393.0389049425385;7394.186639469762;7470.962645186739;32724.672753272163;43193.13613727645;43281.56718432775;44045.33931990959;44061.77969110262;50323.49676503188;58618.12072818996;59364.03179840904;59488.405115948284;59990;9345.859812102959;9350.719478415658;9498.905010949802;9516.143547080796;9570.760730981781;10410.531526081497;10590.8940910593;10732.034116929513;10871.782564348827;10942.507587158756;9533.777449292947;9553.522323883639;9710.6115755369;9796.65711700087;9824;9127.452352858849;9357.92383875863;9454.716358509255;9474;9643;8960.238379737535;9048.457092574467;9070.455772653662;9158.267338612763;9248.075192480967;8489.915100849159;8526.616302266537;8668.263197628272;8676.826463470568;8738.563071846509;7879.84240315202;7976.800579985546;8114.553699546582;8319.459235149696;8365.205305495969 16:35:47 INFO - PROCESS | 31477 | __end_tp_report 16:35:47 INFO - PROCESS | 31477 | __start_cc_report 16:35:47 INFO - PROCESS | 31477 | _x_x_mozilla_cycle_collect,382 16:35:47 INFO - PROCESS | 31477 | __end_cc_report 16:35:47 INFO - PROCESS | 31477 | __startTimestamp1468020947108__endTimestamp 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | ------- Summary: start ------- 16:35:47 INFO - PROCESS | 31477 | Number of tests: 6 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | [#0] dojo.html Cycles:165 Average:4733.79 Median:4878.68 stddev:619.04 (12.7%) stddev-sans-first:597.23 16:35:47 INFO - PROCESS | 31477 | Values: 2570.6 2603.6 2619.2 2632.5 2648.7 4806.5 4855.1 4868.0 4893.1 4898.6 4635.9 4685.7 4708.2 4761.7 4765.5 4382.9 4441.3 4465.6 4516.4 4572.2 4439.0 4490.7 4524.2 4541.9 4557.2 174.4 4423.0 4427.6 4454.4 4510.7 4472.1 4476.5 4485.5 4498.2 4515.1 4432.0 4432.4 4432.8 4487.8 4498.9 4741.3 4823.0 4845.4 4878.7 4884.8 5170.1 5199.1 5206.6 5208.2 5241.7 4977.2 5220.9 5222.5 5239.1 5245.3 4761.2 4766.5 4803.8 4805.1 4809.2 5236.6 5254.3 5291.1 5294.8 5353.7 4939.6 5048.7 5060.4 5068.1 5069.2 5160.0 5204.1 5219.6 5226.6 5284.4 5152.3 5160.2 5174.8 5175.1 5229.6 4912.9 4988.0 5037.1 5122.2 5124.7 4329.8 4343.0 4397.1 4422.2 4460.1 3791.4 3816.1 3852.5 3859.5 3917.2 4301.1 4405.4 4435.8 4442.5 4462.9 5237.6 5251.5 5255.3 5263.8 5287.1 4429.2 4471.8 4496.3 4523.8 4527.3 4478.8 4498.7 4521.5 4544.5 4658.3 4691.5 4726.6 4728.2 4739.2 4750.9 5049.3 5056.6 5061.7 5092.6 5140.2 4707.3 4734.3 4770.7 4817.6 4821.4 5088.0 5090.9 5098.7 5113.9 5154.2 5068.1 5172.0 5174.0 5178.2 5190.1 4951.5 4986.4 4994.5 4997.1 5016.3 4996.0 5005.5 5006.8 5008.3 5061.8 4780.4 4896.1 4951.6 4953.7 5004.3 4918.4 5001.4 5057.5 5077.3 5176.8 4949.7 4963.5 4969.5 4983.1 5025.5 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | [#1] ext.html Cycles:180 Average:46433.44 Median:35213.74 stddev:88496.96 (251.3%) stddev-sans-first:88678.16 16:35:47 INFO - PROCESS | 31477 | Values: 549.2 556.6 570.7 579.6 586.9 33057.3 33999.5 37899.2 38012.0 38147.8 37669.1 38385.4 38471.0 38546.2 38563.8 7486.7 7752.3 7797.1 7820.7 7835.8 7673.0 7774.3 7810.3 7854.3 7857.2 7833.3 7835.1 7853.6 7855.9 7871.3 7588.6 7745.6 7801.2 7810.9 7826.4 7662.1 7671.8 7744.5 7749.5 7753.8 35291.5 35669.8 35858.3 36012.6 36075.5 2420.5 2741.3 3091.2 3127.5 3214.9 27789.3 28170.0 28272.7 28495.1 29061.6 2777.9 2821.6 2832.3 2861.3 2901.3 231224.0 235943.0 237032.0 238555.0 239133.0 28766.7 29108.1 29218.9 29315.9 32867.7 33589.0 33835.7 33899.7 33992.8 34116.8 13728.4 13741.6 13750.1 13756.4 13898.4 11707.3 11725.5 11745.9 11758.1 11936.8 8005.6 8017.9 8054.1 8069.0 8087.0 3102.6 3110.5 3133.4 3172.4 3179.3 784.8 798.8 850.6 870.3 909.0 26952.7 27310.9 27995.9 28408.0 28483.1 894.1 906.5 914.8 938.3 943.6 14119.6 14505.5 14882.9 15116.5 15118.2 397979.0 399190.0 399577.0 400162.0 401822.0 49652.5 49654.5 49984.8 50208.5 50376.7 1120.1 1155.7 1156.9 1161.5 1163.0 26351.0 27166.6 27668.9 27972.9 28124.2 43415.3 43523.3 43827.6 43882.0 44008.8 343566.0 347038.0 347234.0 347680.0 347760.0 25689.2 25713.7 25833.5 25928.9 26117.2 43249.6 43673.8 43969.6 43998.0 44372.8 35910.0 37258.2 37682.0 37922.6 38068.8 36809.8 37129.4 37419.3 37521.0 37930.0 34469.0 34819.8 35022.5 35136.0 35392.3 32203.4 32727.0 33488.5 33669.0 33795.0 24839.6 25031.6 25214.9 25453.6 25648.4 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | [#2] jquery.html Cycles:180 Average:23836.97 Median:4621.50 stddev:62958.60 (1362.3%) stddev-sans-first:63110.55 16:35:47 INFO - PROCESS | 31477 | Values: 366.9 371.5 373.0 382.8 385.7 16324.9 17291.8 17948.4 18038.1 18068.3 15201.5 16270.6 16416.3 16423.8 17253.8 4717.2 4791.9 4811.6 4894.3 4902.1 3512.3 4782.6 5229.3 5460.8 5528.3 5560.5 5572.2 5577.3 5589.4 5624.0 5464.5 5569.6 5573.1 5580.0 5580.0 5150.9 5337.7 5345.3 5349.0 5360.7 18943.6 20370.5 20419.8 20617.0 21828.2 1705.7 1921.4 2007.7 2372.5 2467.7 9235.9 9426.4 9502.4 9634.4 9660.2 1509.8 1573.2 1578.5 1606.8 1633.5 66143.3 69342.0 70347.0 70464.0 70728.0 10166.8 10213.4 10354.3 10401.1 10525.8 14171.9 14194.0 14214.3 14218.8 14249.9 6969.3 7294.7 7359.5 7450.0 7510.8 6776.7 6843.2 6852.3 6944.1 6973.2 4375.4 4403.4 4414.1 4507.4 4525.8 1703.0 1734.2 1851.4 1884.3 1888.5 726.3 867.2 891.6 892.3 930.4 16559.8 16596.2 16631.9 16861.0 17150.1 805.7 820.8 828.1 888.3 901.4 8862.3 9228.2 9587.8 9630.8 9776.6 377554.0 380575.0 383465.0 384270.0 384300.0 47400.3 58639.0 62531.1 76815.2 78430.0 901.6 904.5 910.2 910.9 914.6 14488.5 15038.8 15267.5 15353.0 15514.7 36222.6 36897.6 37377.4 38262.0 38366.6 52482.2 53864.0 53999.2 54099.0 55772.0 6814.0 7086.4 7167.2 7360.9 7821.8 8782.7 11301.4 11331.9 12326.4 12565.2 10087.6 10175.6 10184.4 10303.2 10424.4 10591.8 10981.4 10989.8 11045.4 11124.8 10148.6 10376.5 10465.0 10510.7 10715.6 7586.4 7661.5 7793.5 7884.2 7899.0 9104.2 9329.4 9742.5 9814.3 10200.3 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | [#3] mootools.html Cycles:180 Average:4985.91 Median:5054.15 stddev:2776.84 (54.9%) stddev-sans-first:2771.49 16:35:47 INFO - PROCESS | 31477 | Values: 1391.6 1421.2 1531.2 1709.8 1732.2 6696.1 7273.6 7326.9 7740.5 7744.8 7391.7 7630.5 7657.7 7692.5 7703.2 2165.4 2191.7 2204.5 2208.4 2210.4 2154.8 2201.6 2205.4 2218.4 2221.5 2200.9 2205.6 2213.3 2218.6 2222.6 2153.6 2159.1 2161.2 2161.2 2162.5 2041.5 2083.8 2093.9 2094.3 2100.6 7397.6 7660.3 7682.9 7690.7 7731.1 4335.8 4452.0 4457.1 4457.2 4471.1 2815.9 2829.6 2851.5 2872.5 2874.6 855.5 885.5 886.4 899.8 904.1 10272.8 10292.5 10311.6 10312.6 10336.1 4013.1 4158.2 4223.1 4272.1 4318.1 8829.2 8881.4 8894.4 8933.8 8945.9 4482.4 4504.0 4510.3 4550.9 4551.3 3471.8 3967.5 4100.3 4106.0 4118.3 3544.6 3553.5 3636.2 3676.9 3686.1 1192.6 1212.2 1214.2 1219.5 1226.1 692.6 742.7 811.4 836.9 843.1 5020.4 5087.9 5088.5 5137.8 5169.8 390.8 396.3 396.8 397.5 420.6 3462.6 3586.1 3612.9 3641.0 3663.9 8971.5 9224.6 9341.0 9343.4 9361.1 8610.7 9247.5 9288.6 9293.9 9299.9 6584.8 6798.4 6906.7 6909.6 6961.9 5104.2 5113.2 5204.3 5261.7 5321.5 8290.6 8295.5 8307.2 8364.2 8389.1 8218.5 8438.1 8570.3 8678.8 8685.3 6347.4 6924.8 7100.1 7141.3 7312.2 7400.2 7412.6 7434.3 7448.7 7481.2 6571.2 6691.8 6823.7 6831.7 6853.1 6655.0 6661.6 6687.1 6751.4 6822.4 5867.4 6003.3 6010.4 6045.2 6053.1 4251.1 4271.4 4284.8 4356.2 4382.0 4232.0 4237.4 4286.4 4298.2 4318.2 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | [#4] prototype.html Cycles:180 Average:4409.16 Median:5079.23 stddev:1308.61 (25.8%) stddev-sans-first:1275.01 16:35:47 INFO - PROCESS | 31477 | Values: 278.0 278.2 284.9 285.2 290.4 5121.3 5126.3 5139.4 5163.5 5167.4 5051.3 5056.9 5059.9 5064.2 5064.9 3774.3 3847.5 3934.7 3941.6 3959.6 3932.3 3945.8 3968.3 3969.8 3972.2 3923.8 3936.7 3939.4 3960.9 3972.4 3779.0 3919.8 3934.6 3943.9 3946.1 3423.9 3435.8 3440.9 3445.6 3445.7 4945.3 4963.1 4967.8 4968.6 4976.2 4401.5 4498.7 4510.3 4512.9 4528.8 5202.3 5248.2 5274.4 5278.8 5287.7 3715.8 3726.3 3746.6 3756.3 3769.4 5397.7 5553.8 5562.4 5572.8 5592.5 3813.7 3822.4 3830.4 3832.0 3834.5 3872.3 3882.7 3885.4 3891.8 3927.7 4488.8 4497.3 4506.2 4511.2 4520.3 5230.5 5281.4 5353.6 5367.7 5378.6 1343.5 1347.9 1350.2 1351.5 1352.1 829.6 846.9 854.5 862.6 877.8 4541.3 4636.8 4644.4 4650.7 4678.8 5491.3 5521.5 5524.3 5540.5 5605.7 4443.8 4682.7 4695.9 4698.3 4717.8 2913.5 2951.7 2957.9 2965.6 2968.8 5115.3 5142.4 5159.4 5162.7 5174.4 5451.9 5670.9 5681.2 5683.4 5703.5 5111.0 5115.5 5132.1 5149.7 5173.7 5526.7 5536.8 5537.8 5558.2 5561.3 5230.3 5368.8 5401.8 5402.4 5424.9 5093.6 5103.5 5115.3 5123.8 5170.9 3447.4 3470.0 3481.4 3502.5 3503.1 5004.5 5248.7 5559.5 5567.5 5627.4 5397.5 5402.1 5411.5 5431.6 5437.8 5197.9 5394.7 5419.9 5424.0 5484.8 5291.9 5302.2 5311.9 5314.1 5342.9 5141.2 5226.2 5415.4 5428.3 5489.0 5285.7 5289.3 5295.8 5296.6 5361.5 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - PROCESS | 31477 | [#5] yui.html Cycles:180 Average:16892.74 Median:31531.99 stddev:24366.67 (77.3%) stddev-sans-first:24404.54 16:35:47 INFO - PROCESS | 31477 | Values: 657.9 661.8 665.1 738.0 751.5 12847.6 13179.4 13626.3 13679.9 13687.1 13164.7 13566.5 13571.7 13635.7 13647.9 626.3 757.5 760.2 773.5 782.4 792.6 795.3 797.1 799.4 799.4 835.6 836.7 837.3 837.6 842.1 827.0 829.5 832.4 832.9 833.5 612.8 615.2 615.2 616.1 618.1 13039.4 13104.1 13186.3 13196.4 13245.6 13630.0 14026.6 14135.6 14141.8 14406.1 9768.5 9795.8 9923.5 10748.8 10810.6 4239.7 4280.4 4289.5 4296.6 4421.4 61293.7 61810.4 61849.7 62437.7 63089.0 10119.6 10285.1 10596.0 10649.9 10722.5 17396.9 17637.6 17735.4 17850.9 18369.4 6674.9 6714.7 6759.7 6774.3 6987.9 3507.8 3650.7 3720.6 3743.3 3746.9 2698.6 2799.2 2802.3 2807.7 2807.7 479.1 485.5 494.1 499.4 501.8 464.5 517.0 570.8 597.5 603.3 7261.9 7485.2 7788.5 7900.3 8072.9 492.3 544.6 553.9 564.4 583.2 3561.7 3780.2 3794.8 3891.3 4035.3 90530.0 91723.5 92182.5 92580.0 92800.0 81626.6 82827.6 83869.6 83897.6 84520.0 69534.7 70846.6 71663.6 72016.9 73304.6 7168.5 7266.8 7393.0 7394.2 7471.0 32724.7 43193.1 43281.6 44045.3 44061.8 50323.5 58618.1 59364.0 59488.4 59990.0 9345.9 9350.7 9498.9 9516.1 9570.8 10410.5 10590.9 10732.0 10871.8 10942.5 9533.8 9553.5 9710.6 9796.7 9824.0 9127.5 9357.9 9454.7 9474.0 9643.0 8960.2 9048.5 9070.5 9158.3 9248.1 8489.9 8526.6 8668.3 8676.8 8738.6 7879.8 7976.8 8114.6 8319.5 8365.2 16:35:47 INFO - PROCESS | 31477 | -------- Summary: end -------- 16:35:47 INFO - PROCESS | 31477 | 16:35:47 INFO - TEST-INFO | 31477: exit 0 16:35:47 INFO - TEST-OK | dromaeo_css | took 1115165ms 16:35:47 INFO - TEST-START | kraken 16:35:47 INFO - operating with platform_type : linux_ 16:35:47 INFO - Initialising browser for kraken test... 16:35:47 INFO - TEST-INFO | started process 31722 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGrHQR3/profile http://localhost:54240/getInfo.html) 16:35:52 INFO - PROCESS | 31722 | __metrics Screen width/height:1600/1200 16:35:52 INFO - PROCESS | 31722 | colorDepth:24 16:35:52 INFO - PROCESS | 31722 | Browser inner width/height: 1024/697 16:35:52 INFO - PROCESS | 31722 | __metrics 16:35:53 ERROR - PROCESS | 31722 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2957: TypeError: this.tabs is undefined 16:35:54 INFO - TEST-INFO | 31722: exit 0 16:35:54 INFO - Browser initialized. 16:35:54 INFO - Running cycle 1/1 for kraken test... 16:35:54 INFO - TEST-INFO | started process 31846 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpGrHQR3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:36:06 INFO - PROCESS | 31846 | RSS: Main: 168992768 16:36:06 INFO - PROCESS | 31846 | 16:36:08 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54240/tests/kraken/driver.html?testName=audio-beat-detection) 16:36:09 INFO - PROCESS | 31846 | RSS: Main: 174440448 16:36:09 INFO - PROCESS | 31846 | 16:36:12 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54240/tests/kraken/driver.html?testName=audio-dft) 16:36:13 INFO - PROCESS | 31846 | RSS: Main: 173944832 16:36:13 INFO - PROCESS | 31846 | 16:36:16 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54240/tests/kraken/driver.html?testName=audio-fft) 16:36:16 INFO - PROCESS | 31846 | RSS: Main: 168628224 16:36:16 INFO - PROCESS | 31846 | 16:36:18 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54240/tests/kraken/driver.html?testName=audio-oscillator) 16:36:19 INFO - PROCESS | 31846 | RSS: Main: 170225664 16:36:19 INFO - PROCESS | 31846 | 16:36:21 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:36:22 INFO - PROCESS | 31846 | RSS: Main: 170233856 16:36:22 INFO - PROCESS | 31846 | 16:36:25 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54240/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:26 INFO - PROCESS | 31846 | RSS: Main: 170119168 16:36:26 INFO - PROCESS | 31846 | 16:36:29 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54240/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:30 INFO - PROCESS | 31846 | RSS: Main: 167153664 16:36:30 INFO - PROCESS | 31846 | 16:36:33 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54240/tests/kraken/driver.html?testName=json-parse-financial) 16:36:34 INFO - PROCESS | 31846 | RSS: Main: 168816640 16:36:34 INFO - PROCESS | 31846 | 16:36:35 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:36 INFO - PROCESS | 31846 | RSS: Main: 169062400 16:36:36 INFO - PROCESS | 31846 | 16:36:38 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:38 INFO - PROCESS | 31846 | RSS: Main: 168951808 16:36:38 INFO - PROCESS | 31846 | 16:36:41 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:41 INFO - PROCESS | 31846 | RSS: Main: 169316352 16:36:41 INFO - PROCESS | 31846 | 16:36:44 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:45 INFO - PROCESS | 31846 | RSS: Main: 169865216 16:36:45 INFO - PROCESS | 31846 | 16:36:48 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:48 INFO - PROCESS | 31846 | RSS: Main: 169893888 16:36:48 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | Cycle 1(1): loaded http://localhost:54240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54240/tests/kraken/driver.html?testName=ai-astar) 16:36:50 INFO - PROCESS | 31846 | RSS: Main: 170684416 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | __start_tp_report 16:36:50 INFO - PROCESS | 31846 | _x_x_mozilla_page_load 16:36:50 INFO - PROCESS | 31846 | _x_x_mozilla_page_load_details 16:36:50 INFO - PROCESS | 31846 | |i|pagename|runs| 16:36:50 INFO - PROCESS | 31846 | |0;ai-astar;105;114;108;113;115;115;117;116;113;114 16:36:50 INFO - PROCESS | 31846 | |1;audio-beat-detection;154;126;145;190;125;158;160;125;188;131 16:36:50 INFO - PROCESS | 31846 | |2;audio-dft;163;163;165;174;166;165;163;165;165;161 16:36:50 INFO - PROCESS | 31846 | |3;audio-fft;77;76;79;151;79;76;77;77;154;80 16:36:50 INFO - PROCESS | 31846 | |4;audio-oscillator;85;85;141;86;85;87;135;86;87;135 16:36:50 INFO - PROCESS | 31846 | |5;imaging-gaussian-blur;118;118;118;118;117;118;119;117;119;118 16:36:50 INFO - PROCESS | 31846 | |6;imaging-darkroom;149;148;148;147;148;152;148;148;148;147 16:36:50 INFO - PROCESS | 31846 | |7;imaging-desaturate;97;96;96;96;96;96;96;96;96;96 16:36:50 INFO - PROCESS | 31846 | |8;json-parse-financial;63;63;62;65;63;63;63;64;64;64 16:36:50 INFO - PROCESS | 31846 | |9;json-stringify-tinderbox;64;65;65;65;65;68;64;66;65;65 16:36:50 INFO - PROCESS | 31846 | |10;stanford-crypto-aes;82;80;81;82;81;85;85;85;84;78 16:36:50 INFO - PROCESS | 31846 | |11;stanford-crypto-ccm;136;134;136;149;146;135;133;148;176;133 16:36:50 INFO - PROCESS | 31846 | |12;stanford-crypto-pbkdf2;171;172;172;181;177;171;170;169;170;169 16:36:50 INFO - PROCESS | 31846 | |13;stanford-crypto-sha256-iterative;68;70;69;68;73;69;71;68;67;68 16:36:50 INFO - PROCESS | 31846 | __end_tp_report 16:36:50 INFO - PROCESS | 31846 | __start_cc_report 16:36:50 INFO - PROCESS | 31846 | _x_x_mozilla_cycle_collect,993 16:36:50 INFO - PROCESS | 31846 | __end_cc_report 16:36:50 INFO - PROCESS | 31846 | __startTimestamp1468021010862__endTimestamp 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | ------- Summary: start ------- 16:36:50 INFO - PROCESS | 31846 | Number of tests: 14 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.50 stddev:3.71 (3.2%) stddev-sans-first:2.57 16:36:50 INFO - PROCESS | 31846 | Values: 105.0 114.0 108.0 113.0 115.0 115.0 117.0 116.0 113.0 114.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:156.00 stddev:24.58 (15.8%) stddev-sans-first:26.03 16:36:50 INFO - PROCESS | 31846 | Values: 154.0 126.0 145.0 190.0 125.0 158.0 160.0 125.0 188.0 131.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#2] audio-dft Cycles:10 Average:165.00 Median:165.00 stddev:3.50 (2.1%) stddev-sans-first:3.63 16:36:50 INFO - PROCESS | 31846 | Values: 163.0 163.0 165.0 174.0 166.0 165.0 163.0 165.0 165.0 161.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#3] audio-fft Cycles:10 Average:92.60 Median:77.00 stddev:31.61 (41.0%) stddev-sans-first:33.02 16:36:50 INFO - PROCESS | 31846 | Values: 77.0 76.0 79.0 151.0 79.0 76.0 77.0 77.0 154.0 80.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:85.50 stddev:24.77 (29.0%) stddev-sans-first:25.57 16:36:50 INFO - PROCESS | 31846 | Values: 85.0 85.0 141.0 86.0 85.0 87.0 135.0 86.0 87.0 135.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#5] imaging-gaussian-blur Cycles:10 Average:118.00 Median:118.00 stddev:0.67 (0.6%) stddev-sans-first:0.71 16:36:50 INFO - PROCESS | 31846 | Values: 118.0 118.0 118.0 118.0 117.0 118.0 119.0 117.0 119.0 118.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#6] imaging-darkroom Cycles:10 Average:148.30 Median:148.00 stddev:1.42 (1.0%) stddev-sans-first:1.48 16:36:50 INFO - PROCESS | 31846 | Values: 149.0 148.0 148.0 147.0 148.0 152.0 148.0 148.0 148.0 147.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 16:36:50 INFO - PROCESS | 31846 | Values: 97.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:63.50 stddev:0.84 (1.3%) stddev-sans-first:0.88 16:36:50 INFO - PROCESS | 31846 | Values: 63.0 63.0 62.0 65.0 63.0 63.0 63.0 64.0 64.0 64.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:1.14 (1.7%) stddev-sans-first:1.12 16:36:50 INFO - PROCESS | 31846 | Values: 64.0 65.0 65.0 65.0 65.0 68.0 64.0 66.0 65.0 65.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:2.41 (2.9%) stddev-sans-first:2.55 16:36:50 INFO - PROCESS | 31846 | Values: 82.0 80.0 81.0 82.0 81.0 85.0 85.0 85.0 84.0 78.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#11] stanford-crypto-ccm Cycles:10 Average:142.60 Median:141.00 stddev:13.33 (9.5%) stddev-sans-first:13.93 16:36:50 INFO - PROCESS | 31846 | Values: 136.0 134.0 136.0 149.0 146.0 135.0 133.0 148.0 176.0 133.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.20 Median:171.50 stddev:3.85 (2.2%) stddev-sans-first:4.06 16:36:50 INFO - PROCESS | 31846 | Values: 171.0 172.0 172.0 181.0 177.0 171.0 170.0 169.0 170.0 169.0 16:36:50 INFO - PROCESS | 31846 | 16:36:50 INFO - PROCESS | 31846 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:69.00 stddev:1.79 (2.6%) stddev-sans-first:1.86 16:36:50 INFO - PROCESS | 31846 | Values: 68.0 70.0 69.0 68.0 73.0 69.0 71.0 68.0 67.0 68.0 16:36:50 INFO - PROCESS | 31846 | -------- Summary: end -------- 16:36:50 INFO - PROCESS | 31846 | 16:36:51 INFO - TEST-INFO | 31846: exit 0 16:36:51 INFO - TEST-OK | kraken | took 64095ms 16:36:51 INFO - SUITE-END | took 1179s 16:36:52 INFO - Completed test suite (00:19:39) 16:36:52 INFO - javascript benchmark 16:36:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6273.115940393524, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2570.627259047438, 2603.6485074514935, 2619.201143651186, 2632.460345629146, 2648.6556747622794, 4806.495317991608, 4855.077535268301, 4867.975660121699, 4893.094777466171, 4898.632602554805, 4635.907281854361, 4685.672002959795, 4708.22314318138, 4761.69049011814, 4765.475797662254, 4382.868513944587, 4441.2893936970095, 4465.575770301816, 4516.367708520794, 4572.245579479382, 4438.977805110986, 4490.685652004361, 4524.185646583614, 4541.886452838672, 4557.20248956433, 174.36806389658454, 4423.049044455457, 4427.6015158635555, 4454.35411865281, 4510.706804057742, 4472.105578884204, 4476.462824461053, 4485.529019452943, 4498.167838949805, 4515.05183911379, 4432.0, 4432.357308190294, 4432.844850430251, 4487.753173575452, 4498.8875278117985, 4741.2651039089, 4823.03539292143, 4845.4185497740145, 4878.682885612424, 4884.829030983934, 5170.073458958808, 5199.14214154664, 5206.557442617382, 5208.218767184915, 5241.68549887008, 4977.178765503688, 5220.895582088375, 5222.451642577551, 5239.1093514102695, 5245.291885595434, 4761.190597598416, 4766.52334766524, 4803.759812009421, 4805.063012712488, 4809.2064809306075, 5236.554892834151, 5254.316938798008, 5291.100512912737, 5294.788208471618, 5353.705546194997, 4939.629527785431, 5048.7475626219275, 5060.367454068241, 5068.113080211022, 5069.188929771219, 5160.045391602565, 5204.141316682705, 5219.634625576249, 5226.5818734501145, 5284.445133261029, 5152.330197074356, 5160.200168973815, 5174.818881339135, 5175.094358487249, 5229.607779416559, 4912.852614421573, 4987.950120498748, 5037.0681423936685, 5122.231665250242, 5124.666896651705, 4329.761863097497, 4343.0, 4397.098594788061, 4422.226110430662, 4460.130274596422, 3791.3933770597123, 3816.065064059323, 3852.5376954765047, 3859.4982652255026, 3917.196974620196, 4301.118270754488, 4405.427294451701, 4435.800388982503, 4442.48911375196, 4462.866114016585, 5237.6071794615555, 5251.52736253739, 5255.343082114736, 5263.789448422097, 5287.07476191665, 4429.2027435062, 4471.776411179428, 4496.280595104768, 4523.7964291606295, 4527.275635898241, 4478.843240486567, 4498.685092043525, 4521.480029796616, 4544.500104988449, 4658.34783130362, 4691.507391723888, 4726.574608285271, 4728.196206644948, 4739.170645137087, 4750.928736068892, 5049.293098966221, 5056.57019153376, 5061.670991385548, 5092.592592592658, 5140.177571588678, 4707.317438971399, 4734.26618874075, 4770.7137571744415, 4817.638677099161, 4821.397325334333, 5088.0, 5090.872728181677, 5098.694078355311, 5113.948860511347, 5154.201098829687, 5068.062408454448, 5172.043172013189, 5174.042802081627, 5178.197379406198, 5190.117679994334, 4951.5048495150195, 4986.376702912136, 4994.4755800640405, 4997.100521905946, 5016.347874776402, 4996.025774973845, 5005.549500544968, 5006.824761133343, 5008.3238762768015, 5061.797528098835, 4780.378550788514, 4896.069746748107, 4951.579115775199, 4953.72754498506, 5004.299398084198, 4918.43438004634, 5001.424836143745, 5057.544820966131, 5077.263796749525, 5176.844694659167, 4949.7030178189425, 4963.503649635008, 4969.47820478859, 4983.053219888209, 5025.547700706954], "value": 4695.956998269063, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [549.1926867504769, 556.6437480012792, 570.7346084070906, 579.5682216748523, 586.8855573163235, 33057.33885322292, 33999.490007649874, 37899.24201515968, 38012.0, 38147.80926095373, 37669.058273543174, 38385.42421863674, 38471.038224044416, 38546.22907541847, 38563.807180964126, 7486.663100160492, 7752.3410510106705, 7797.103333116679, 7820.726274580391, 7835.8432831343625, 7672.961635191844, 7774.2614451627005, 7810.297073263405, 7854.332381747558, 7857.174996625357, 7833.315837095023, 7835.059792824841, 7853.646585903648, 7855.921440785604, 7871.330936870373, 7588.58262795546, 7745.573993430359, 7801.180876007994, 7810.921890781133, 7826.373890088779, 7662.08055033394, 7671.808204794925, 7744.49660772048, 7749.457537972344, 7753.8449231015065, 35291.4706279406, 35669.82165089158, 35858.28283434317, 36012.63987360145, 36075.458868117, 2420.4674971506242, 2741.3420779012895, 3091.2271932016997, 3127.53087036944, 3214.9357012859846, 27789.305267368276, 28170.0, 28272.717272827213, 28495.145145645663, 29061.564076538867, 2777.8749956251813, 2821.604975303459, 2832.3485598312295, 2861.2846788302923, 2901.2601786544296, 231224.0, 235943.0, 237032.0, 238555.0, 239133.0, 28766.712332876403, 29108.126756197347, 29218.853905730335, 29315.85342073276, 32867.67132328694, 33589.0, 33835.66164338374, 33899.66100338965, 33992.83003584966, 34116.829415852764, 13728.382222799997, 13741.587752367444, 13750.106243094167, 13756.449742010207, 13898.444062237599, 11707.2975621463, 11725.530978760924, 11745.88254117465, 11758.05935525156, 11936.820947685792, 8005.639746211435, 8017.919820801834, 8054.073781515201, 8068.99137607799, 8086.959565202136, 3102.565640810288, 3110.5178697301953, 3133.3576616793957, 3172.4289627867206, 3179.2528755742383, 784.8430313937235, 798.7883210949103, 850.6257246811383, 870.2820173357006, 908.9681861134828, 26952.73047269502, 27310.863445682644, 27995.860020699765, 28408.0, 28483.145505634864, 894.1148263219496, 906.4833045164193, 914.8033172867732, 938.2587755673078, 943.5989704375613, 14119.576412707635, 14505.49230776939, 14882.851171488146, 15116.54650360491, 15118.244087795567, 397979.0, 399190.0, 399577.0, 400162.0, 401822.0, 49652.50347496479, 49654.503454964986, 49984.75007624939, 50208.49791502038, 50376.74811625919, 1120.1207052463776, 1155.6821873984554, 1156.9074474042225, 1161.5469966712988, 1162.9709257268466, 26351.0, 27166.592501112893, 27668.86165569159, 27972.860135699193, 28124.156275311772, 43415.34876976785, 43523.347149793415, 43827.561724383624, 43882.0, 44008.779956100014, 343566.0, 347038.0, 347234.0, 347680.0, 347760.0, 25689.201755876908, 25713.742862571136, 25833.48333033291, 25928.870355648854, 26117.216483505526, 43249.567504325816, 43673.78163109164, 43969.560304397826, 43998.0, 44372.77813610911, 35910.0, 37258.205634576974, 37682.0, 37922.62077379191, 38068.80965595154, 36809.81595092007, 37129.443058354685, 37419.251614968096, 37521.0, 37930.0, 34469.0, 34819.82590087135, 35022.47466288059, 35136.0, 35392.29215415626, 32203.35593288168, 32727.0, 33488.497672534446, 33669.0, 33795.0, 24839.60586013295, 25031.550286684876, 25214.873925630254, 25453.61819572745, 25648.358791030372], "value": 16070.804227196972, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.91194113412774, 371.5096073183397, 372.998135009325, 382.7684251028126, 385.68373933374625, 16324.91837540812, 17291.827081729178, 17948.371806986757, 18038.098095095258, 18068.277268909227, 15201.543953681406, 16270.593235169126, 16416.261268242928, 16423.83576164238, 17253.827461725377, 4717.198076327024, 4791.880202994918, 4811.639127065467, 4894.3392641993405, 4902.068606964684, 3512.3150985557827, 4782.593479554242, 5229.3201883755055, 5460.808871689492, 5528.336599608053, 5560.526460487974, 5572.164175373706, 5577.3028371453565, 5589.441055894398, 5624.0439125348785, 5464.508194262516, 5569.554435645138, 5573.108302671553, 5579.972100139474, 5579.995600791855, 5150.896982060338, 5337.70642614656, 5345.278387417727, 5349.037173308803, 5360.678359298416, 18943.62112757737, 20370.49073773153, 20419.79580204194, 20617.0, 21828.236011739515, 1705.6673948580033, 1921.4331772127257, 2007.6787713965696, 2372.5254949010096, 2467.716212635534, 9235.937867145298, 9426.387284826533, 9502.429854208769, 9634.42193468387, 9660.227181825438, 1509.7810817431412, 1573.1976691887105, 1578.486991727693, 1606.7991501062368, 1633.4527933142413, 66143.3385666142, 69342.0, 70346.99999999949, 70464.0, 70728.0, 10166.796664066826, 10213.387196768217, 10354.275200735876, 10401.115905147995, 10525.789484210272, 14171.92914035444, 14194.0, 14214.289285535682, 14218.786718199235, 14249.928750356183, 6969.257685578606, 7294.738631024294, 7359.4848360615315, 7450.0, 7510.812229694301, 6776.728930842711, 6843.247242803288, 6852.349026842442, 6944.062551555577, 6973.163220413583, 4375.387445757597, 4403.383526306319, 4414.139242847678, 4507.391502147234, 4525.796339164745, 1702.9659406811795, 1734.2282684205659, 1851.4445666300055, 1884.3498992847447, 1888.5373083594607, 726.2664708644202, 867.2195024477894, 891.6121487153109, 892.3262936482914, 930.3998920696113, 16559.840811143105, 16596.17019149038, 16631.91684041572, 16861.0, 17150.142492875308, 805.7260531419346, 820.8440396324798, 828.1015098618067, 888.3159966825508, 901.4456109492614, 8862.291016718647, 9228.215601673799, 9587.808243835085, 9630.759231019281, 9776.560054797552, 377554.0, 380575.0, 383465.0, 384270.0, 384300.0, 47400.28899566509, 58639.0, 62531.062034069524, 76815.23184768192, 78430.0, 901.6393442623134, 904.5391692416699, 910.1990248581338, 910.882198887589, 914.5518695838991, 14488.492902748354, 15038.774418383515, 15267.465638702592, 15353.0, 15514.689706206038, 36222.63777362193, 36897.63102368942, 37377.4393384094, 38262.04344891289, 38366.616333836304, 52482.212766809294, 53864.0, 53999.19001214906, 54099.0, 55772.0, 6814.0, 7086.362227399559, 7167.247439019036, 7360.926390736024, 7821.804454888445, 8782.736517904474, 11301.378424186749, 11331.943340283246, 12326.445309961253, 12565.246085234916, 10087.646932357333, 10175.643852465128, 10184.439855808001, 10303.227257955834, 10424.374537527772, 10591.841122383017, 10981.3960232188, 10989.780204396027, 11045.392503412068, 11124.777504450027, 10148.644797432054, 10376.533056012346, 10464.953504649475, 10510.737231569272, 10715.571377144826, 7586.393088553013, 7661.502002369828, 7793.506078054968, 7884.172161922916, 7899.0, 9104.226140778108, 9329.393589416666, 9742.512874356113, 9814.31299809035, 10200.28597998133], "value": 8150.445785552664, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.6173052410586, 1421.239636794315, 1531.1884701108415, 1709.8119206887231, 1732.2291580246788, 6696.06255124283, 7273.63631818409, 7326.926730732691, 7740.45816792824, 7744.806379840506, 7391.704331826721, 7630.504017238875, 7657.731979380723, 7692.4615276930635, 7703.229677032294, 2165.415337858781, 2191.6712493126, 2204.526026904215, 2208.403730992631, 2210.4142402263415, 2154.795294447033, 2201.6037113319594, 2205.40454077399, 2218.378853920905, 2221.489057516772, 2200.9449763755874, 2205.63607004844, 2213.269621025058, 2218.5784700906856, 2222.5888210680996, 2153.6446486329737, 2159.136345461812, 2161.2003558683386, 2161.232762369352, 2162.4810045589024, 2041.4794227472048, 2083.8437117216117, 2093.8743675379374, 2094.2565389286888, 2100.6323893318763, 7397.593132377717, 7660.2722741339485, 7682.884756728654, 7690.7308244211745, 7731.072271327421, 4335.848245311399, 4452.0, 4457.086297309076, 4457.24226881431, 4471.0610771737975, 2815.9014434494693, 2829.575563665446, 2851.458222937635, 2872.540393537024, 2874.6345485894244, 855.482433127961, 885.4642941020654, 886.3972498700881, 899.7615631857562, 904.0913881549001, 10272.84590731132, 10292.536835842406, 10311.587536498531, 10312.587496500131, 10336.0697537222, 4013.1171142348635, 4158.2307273154565, 4223.070924396628, 4272.060146767706, 4318.071614602875, 8829.16122968317, 8881.42270752399, 8894.421862579042, 8933.776655583662, 8945.865812012826, 4482.394876691605, 4503.954960450354, 4510.323451482304, 4550.88622784433, 4551.294549344857, 3471.8437670304897, 3967.484227050465, 4100.34394496879, 4105.97947010263, 4118.320477121241, 3544.556930383702, 3553.520274762874, 3636.1819090454774, 3676.9448458273146, 3686.096906257984, 1192.5527927027365, 1212.1878341511208, 1214.228964607463, 1219.5000049979467, 1226.1110694746237, 692.5671455340412, 742.6546655805076, 811.3792948394483, 836.9036562103664, 843.1020962674731, 5020.3724534433195, 5087.923681144785, 5088.465711100355, 5137.845864624067, 5169.767360468788, 390.75187256092585, 396.3025075866496, 396.76987347338473, 397.5388549282876, 420.58992482329484, 3462.567179102612, 3586.1393265616066, 3612.909677258037, 3640.9817950910074, 3663.8900832975055, 8971.461712297283, 9224.584893679801, 9341.0, 9343.439393636403, 9361.110694484149, 8610.741677749553, 9247.537623118951, 9288.628454861864, 9293.860592091123, 9299.907000929905, 6584.8353791155605, 6798.354156355138, 6906.689198985957, 6909.585424874423, 6961.860762784716, 5104.2088476286235, 5113.233015047773, 5204.271402003798, 5261.710605916712, 5321.494458026481, 8290.58547072656, 8295.460795048302, 8307.210814972568, 8364.247217750431, 8389.077201507707, 8218.547979860927, 8438.071812100787, 8570.314374850122, 8678.82642347162, 8685.348598854986, 6347.39699728525, 6924.826879327856, 7100.112485939258, 7141.321574450627, 7312.1591017033725, 7400.185979542353, 7412.555246685216, 7434.293742094493, 7448.664810083667, 7481.17707052213, 6571.244306904576, 6691.76578819739, 6823.658817059227, 6831.658417079226, 6853.109095817711, 6654.966725166537, 6661.600303981777, 6687.063811066358, 6751.426128779109, 6822.420094292041, 5867.354590995073, 6003.279606447254, 6010.368911264254, 6045.183900173421, 6053.061775424816, 4251.06476575153, 4271.423357846776, 4284.764337961442, 4356.150550642595, 4381.978090109529, 4232.047789247395, 4237.449131612871, 4286.399904013503, 4298.20483210607, 4318.201132790444], "value": 3965.733795952621, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [278.0, 278.164116828929, 284.90882917466405, 285.21850130642036, 290.3771410324852, 5121.257417674435, 5126.3335766350365, 5139.408967968685, 5163.45783692712, 5167.405748338942, 5051.292819005343, 5056.898862022757, 5059.898802023958, 5064.24036394541, 5064.873378165547, 3774.282886251617, 3847.5190601174854, 3934.665553427962, 3941.645251927326, 3959.6436320731127, 3932.252871954334, 3945.7829819360068, 3968.2658708138943, 3969.7618142911374, 3972.245273398045, 3923.8430462781453, 3936.7047471439614, 3939.3500072488005, 3960.9207815843665, 3972.36442169253, 3778.9607857839037, 3919.7844118573466, 3934.626210509997, 3943.861964831217, 3946.112124771932, 3423.911414037435, 3435.845386957593, 3440.896773096811, 3445.552078229839, 3445.6898879101, 4945.282933974553, 4963.131451995922, 4967.776450059756, 4968.6025117990475, 4976.2038073908, 4401.515833258339, 4498.730076195438, 4510.300903359985, 4512.91689994399, 4528.796204170821, 5202.323697919284, 5248.239005344242, 5274.367075950872, 5278.762455689464, 5287.656302340336, 3715.777053376806, 3726.329260733067, 3746.569144548357, 3756.286305601927, 3769.3968964965475, 5397.70312632809, 5553.8333849984565, 5562.3881373048935, 5572.832815015556, 5592.5246354059955, 3813.733038687321, 3822.4075268332895, 3830.3871380578976, 3832.0301766130915, 3834.5206849143856, 3872.283627528916, 3882.650561449483, 3885.3977633466857, 3891.8248678809523, 3927.7054220932973, 4488.842890498882, 4497.347884556721, 4506.233940230169, 4511.187986162457, 4520.299353600197, 5230.483159883591, 5281.419043905167, 5353.625246232804, 5367.704776237344, 5378.596605254544, 1343.4760443427072, 1347.905646604748, 1350.1763924006348, 1351.486435154635, 1352.0873410447907, 829.6266679994027, 846.8912408103777, 854.5428195915156, 862.6420035685246, 877.7761670773912, 4541.250693635579, 4636.8145274188655, 4644.396228490275, 4650.67445278834, 4678.7660616969015, 5491.313585801775, 5521.5306698931045, 5524.309461317335, 5540.529055030287, 5605.663660180402, 4443.755593442392, 4682.682072374197, 4695.906081878344, 4698.271767875985, 4717.811287548459, 2913.504704200249, 2951.6605590356953, 2957.8521073946217, 2965.599644048069, 2968.762499000075, 5115.309433226541, 5142.382914050263, 5159.355080614923, 5162.6902385856965, 5174.353205849269, 5451.938305962004, 5670.943290567042, 5681.204631351614, 5683.403242659461, 5703.54371650267, 5110.97444512775, 5115.462876397924, 5132.12753831842, 5149.6910185389, 5173.714691171133, 5526.696031718133, 5536.7785288588, 5537.7784888604, 5558.194061860927, 5561.304836895388, 5230.293910322058, 5368.8389348319615, 5401.810936617199, 5402.378726446509, 5424.864378390414, 5093.592512599057, 5103.515166059218, 5115.314459908578, 5123.769430375716, 5170.948290517197, 3447.3622379859808, 3470.0, 3481.408160612651, 3502.4921386399337, 3503.1242189452637, 5004.524570165829, 5248.685078895279, 5559.52744016747, 5567.498925096761, 5627.352854421631, 5397.487238712316, 5402.135658294654, 5411.458854114557, 5431.619786614899, 5437.755301011385, 5197.896042079062, 5394.703291318858, 5419.918701219563, 5423.972880135574, 5484.753186106715, 5291.894162116813, 5302.231176479466, 5311.9468805313, 5314.070037743457, 5342.866428339323, 5141.2288156776785, 5226.170840206049, 5415.350157981067, 5428.348598168087, 5489.011977843867, 5285.656432331886, 5289.338832645919, 5295.76169072385, 5296.602754793329, 5361.544268737201], "value": 3981.068023810704, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.858560409512, 661.8345413646589, 665.0589415976393, 738.0258059361645, 751.5152726491411, 12847.614571562857, 13179.406926688298, 13626.318684065807, 13679.931600341997, 13687.110337828035, 13164.670883227924, 13566.45734170632, 13571.660708482294, 13635.659108522266, 13647.931760341184, 626.2829060725467, 757.4735558786632, 760.1752098972621, 773.4508498965713, 782.4131901074194, 792.5957761541626, 795.3041089047084, 797.1072398913225, 799.3884678221166, 799.4204201953595, 835.5571547080058, 836.6695155413609, 837.3050368194384, 837.623069618671, 842.149429076634, 827.028241815867, 829.4857188543111, 832.4297855968681, 832.8917240758723, 833.5248908122372, 612.8161551534566, 615.1818081950994, 615.2340336281359, 616.0512810272176, 618.0944915698478, 13039.413226404835, 13104.08271421001, 13186.340682965812, 13196.406161722745, 13245.602631921058, 13630.0, 14026.579202623938, 14135.575932722035, 14141.787873181911, 14406.135631862016, 9768.51157442125, 9795.804083918352, 9923.454210018446, 10748.838767418494, 10810.567577296899, 4239.745615263063, 4280.4221430106845, 4289.549597292302, 4296.591823776736, 4421.447319085114, 61293.6935315325, 61810.38189618136, 61849.690751546404, 62437.68781156065, 63089.0, 10119.59521619127, 10285.125764309967, 10595.993380628828, 10649.9467502662, 10722.517486713117, 17396.913015434842, 17637.647247055243, 17735.379261726037, 17850.91074544619, 18369.357072502396, 6674.933250667431, 6714.731410743516, 6759.729610815512, 6774.288699686561, 6987.89518157228, 3507.8421471033867, 3650.7079433645245, 3720.627937206312, 3743.344914639927, 3746.9063273418383, 2698.6491756071587, 2799.216219458555, 2802.327441414087, 2807.6771171315154, 2807.7051909549614, 479.09451137350436, 485.46841208876225, 494.1327969413656, 499.37827404881153, 501.77169387928404, 464.5076219207651, 516.9689818610896, 570.8373113662586, 597.5428796970322, 603.2971588099815, 7261.8547629047125, 7485.214052524516, 7788.532688038736, 7900.3284720799375, 8072.919270807334, 492.308306828906, 544.5697898660014, 553.9058360078797, 564.4045531963735, 583.1660725163058, 3561.715062795022, 3780.2250538639514, 3794.7533410328792, 3891.2606604745006, 4035.2938235808615, 90530.0, 91723.54138229266, 92182.53908730413, 92580.0, 92800.0, 81626.59186704147, 82827.58586207031, 83869.58065209757, 83897.58051209705, 84520.0, 69534.65232673804, 70846.64576677083, 71663.64168179125, 72016.91974620384, 73304.63347683227, 7168.53404528704, 7266.818329541804, 7393.0389049425385, 7394.186639469762, 7470.962645186739, 32724.672753272163, 43193.13613727645, 43281.56718432775, 44045.33931990959, 44061.77969110262, 50323.49676503188, 58618.12072818996, 59364.03179840904, 59488.405115948284, 59990.0, 9345.859812102959, 9350.719478415658, 9498.905010949802, 9516.143547080796, 9570.760730981781, 10410.531526081497, 10590.8940910593, 10732.034116929513, 10871.782564348827, 10942.507587158756, 9533.777449292947, 9553.522323883639, 9710.6115755369, 9796.65711700087, 9824.0, 9127.452352858849, 9357.92383875863, 9454.716358509255, 9474.0, 9643.0, 8960.238379737535, 9048.457092574467, 9070.455772653662, 9158.267338612763, 9248.075192480967, 8489.915100849159, 8526.616302266537, 8668.263197628272, 8676.826463470568, 8738.563071846509, 7879.84240315202, 7976.800579985546, 8114.553699546582, 8319.459235149696, 8365.205305495969], "value": 6274.634558664687, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 108.0, 113.0, 115.0, 115.0, 117.0, 116.0, 113.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 126.0, 145.0, 190.0, 125.0, 158.0, 160.0, 125.0, 188.0, 131.0], "value": 150.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 165.0, 174.0, 166.0, 165.0, 163.0, 165.0, 165.0, 161.0], "value": 165.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 79.0, 151.0, 79.0, 76.0, 77.0, 77.0, 154.0, 80.0], "value": 92.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 141.0, 86.0, 85.0, 87.0, 135.0, 86.0, 87.0, 135.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 118.0, 118.0, 117.0, 118.0, 119.0, 117.0, 119.0, 118.0], "value": 118.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 148.0, 148.0, 147.0, 148.0, 152.0, 148.0, 148.0, 148.0, 147.0], "value": 148.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 65.0, 63.0, 63.0, 63.0, 64.0, 64.0, 64.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 65.0, 65.0, 68.0, 64.0, 66.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 81.0, 82.0, 81.0, 85.0, 85.0, 85.0, 84.0, 78.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 136.0, 149.0, 146.0, 135.0, 133.0, 148.0, 176.0, 133.0], "value": 142.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 172.0, 172.0, 181.0, 177.0, 171.0, 170.0, 169.0, 170.0, 169.0], "value": 172.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 69.0, 68.0, 73.0, 69.0, 71.0, 68.0, 67.0, 68.0], "value": 69.1, "unit": "score"}]}]} 16:36:52 INFO - Return code: 0 16:36:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:52 ERROR - # TBPL SUCCESS # 16:36:52 INFO - Running post-action listener: _resource_record_post_action 16:36:52 INFO - ##### 16:36:52 INFO - ##### Finished run-tests step (success) 16:36:52 INFO - ##### 16:36:52 INFO - Running post-run listener: _resource_record_post_run 16:36:52 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 4096; Write bytes: 536043520; Read time: 8; Write time: 393276 16:36:52 INFO - TinderboxPrint: CPU usage
13.3% 16:36:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 16:36:52 INFO - TinderboxPrint: I/O write bytes / time
536,043,520 / 393,276 16:36:52 INFO - TinderboxPrint: CPU idle
8,269.9 (86.6%) 16:36:52 INFO - TinderboxPrint: CPU user
1,220.2 (12.8%) 16:36:52 INFO - TinderboxPrint: Swap in / out
0 / 0 16:36:52 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 245989376; Read time: 0; Write time: 227820 16:36:52 INFO - run-tests - Wall time: 1180s; CPU: 13.0%; Read bytes: 4096; Write bytes: 289292288; Read time: 8; Write time: 165424 16:36:52 INFO - Running post-run listener: _upload_blobber_files 16:36:52 INFO - Blob upload gear active. 16:36:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:52 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:36:52 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:36:52 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:36:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:59 INFO - (blobuploader) - INFO - Done attempting. 16:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:00 INFO - (blobuploader) - INFO - Done attempting. 16:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:37:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:37:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:37:01 INFO - (blobuploader) - INFO - Done attempting. 16:37:01 INFO - (blobuploader) - INFO - Iteration through files over. 16:37:01 INFO - Return code: 0 16:37:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:37:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:37:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c85c9001f85e662cb3df60358225cf7f4ca08ee367b0ac085c531900f7e1b1665e77936452e1bc5cc2b252b49d51828d4dcd3b52169a543e44c3f107dac88f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11b36016573b6577627c167934518e276f13a9583f03887fd2f793f7e0820828386514053768feb0c5811202d8479d66d792b53b3caeff4c28ac908c1e32c936", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2c4bd9fcf1d2e2ffa4eae03be8ab35ad87469d9eea0e7c1ebb622d73c855acd67a213986920ca8328e314d3a8655c0a91193ba39a71c731a17f6a6d2ce796745"} 16:37:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:37:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:37:01 INFO - Contents: 16:37:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c85c9001f85e662cb3df60358225cf7f4ca08ee367b0ac085c531900f7e1b1665e77936452e1bc5cc2b252b49d51828d4dcd3b52169a543e44c3f107dac88f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11b36016573b6577627c167934518e276f13a9583f03887fd2f793f7e0820828386514053768feb0c5811202d8479d66d792b53b3caeff4c28ac908c1e32c936", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2c4bd9fcf1d2e2ffa4eae03be8ab35ad87469d9eea0e7c1ebb622d73c855acd67a213986920ca8328e314d3a8655c0a91193ba39a71c731a17f6a6d2ce796745"} 16:37:01 INFO - Running post-run listener: copy_logs_to_upload_dir 16:37:01 INFO - Copying logs to upload dir... 16:37:01 INFO - mkdir: /builds/slave/test/build/upload/logs 16:37:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.632920 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-07-08 16:37:00.244938) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 16:37:00.248404) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c85c9001f85e662cb3df60358225cf7f4ca08ee367b0ac085c531900f7e1b1665e77936452e1bc5cc2b252b49d51828d4dcd3b52169a543e44c3f107dac88f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11b36016573b6577627c167934518e276f13a9583f03887fd2f793f7e0820828386514053768feb0c5811202d8479d66d792b53b3caeff4c28ac908c1e32c936", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2c4bd9fcf1d2e2ffa4eae03be8ab35ad87469d9eea0e7c1ebb622d73c855acd67a213986920ca8328e314d3a8655c0a91193ba39a71c731a17f6a6d2ce796745"} 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.011385 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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/c85c9001f85e662cb3df60358225cf7f4ca08ee367b0ac085c531900f7e1b1665e77936452e1bc5cc2b252b49d51828d4dcd3b52169a543e44c3f107dac88f3a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/11b36016573b6577627c167934518e276f13a9583f03887fd2f793f7e0820828386514053768feb0c5811202d8479d66d792b53b3caeff4c28ac908c1e32c936", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/2c4bd9fcf1d2e2ffa4eae03be8ab35ad87469d9eea0e7c1ebb622d73c855acd67a213986920ca8328e314d3a8655c0a91193ba39a71c731a17f6a6d2ce796745"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 16:37:00.273354) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:37:00.273649) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1468019766.632254-415289389 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004461 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 16:37:00.357828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 16:37:00.358152) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 16:37:00.358426) ========= ========= Total master_lag: 0.16 =========