builder: fx-team_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-028 starttime: 1461304753.59 results: success (0) buildid: 20160421203400 builduid: 2d653f6fc86e4b8e9726dd2d92e81848 revision: 4fa5909cf1e73939ee823442f2c69b0a86474489 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:13.588344) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:13.590509) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:13.590867) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:13.614987) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:13.615315) ========= 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=836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-21 22:59:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 67.2M=0s 2016-04-21 22:59:14 (67.2 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.384997 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.016705) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.017127) ========= 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=836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013970 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.068991) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.069389) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 4fa5909cf1e73939ee823442f2c69b0a86474489 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 4fa5909cf1e73939ee823442f2c69b0a86474489 --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=836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-21 22:59:14,184 truncating revision to first 12 chars 2016-04-21 22:59:14,184 Setting DEBUG logging. 2016-04-21 22:59:14,185 attempt 1/10 2016-04-21 22:59:14,185 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/4fa5909cf1e7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 22:59:14,469 unpacking tar archive at: fx-team-4fa5909cf1e7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.577271 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.661384) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.661720) ========= script_repo_revision: 4fa5909cf1e73939ee823442f2c69b0a86474489 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.662159) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.662487) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 22:59:14.672970) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-04-21 22:59:14.673391) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:59:14 INFO - MultiFileLogger online at 20160421 22:59:14 in /builds/slave/test 22:59:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:59:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:59:14 INFO - {'append_to_log': False, 22:59:14 INFO - 'base_work_dir': '/builds/slave/test', 22:59:14 INFO - 'blob_upload_branch': 'Fx-Team', 22:59:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:59:14 INFO - 'branch': 'Fx-Team', 22:59:14 INFO - 'buildbot_json_path': 'buildprops.json', 22:59:14 INFO - 'config_files': ('talos/linux_config.py',), 22:59:14 INFO - 'default_actions': ('clobber', 22:59:14 INFO - 'read-buildbot-config', 22:59:14 INFO - 'download-and-extract', 22:59:14 INFO - 'populate-webroot', 22:59:14 INFO - 'create-virtualenv', 22:59:14 INFO - 'install', 22:59:14 INFO - 'run-tests'), 22:59:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:59:14 INFO - 'download_minidump_stackwalk': True, 22:59:14 INFO - 'download_symbols': 'ondemand', 22:59:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:59:14 INFO - 'tooltool.py': '/tools/tooltool.py', 22:59:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:59:14 INFO - '/tools/misc-python/virtualenv.py')}, 22:59:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:59:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:59:14 INFO - 'installer_path': 'installer.exe', 22:59:14 INFO - 'log_level': 'info', 22:59:14 INFO - 'log_name': 'talos', 22:59:14 INFO - 'log_to_console': True, 22:59:14 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:59:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:59:14 INFO - 'opt_config_files': (), 22:59:14 INFO - 'pip_index': False, 22:59:14 INFO - 'sps_profile': False, 22:59:14 INFO - 'sps_profile_interval': 0, 22:59:14 INFO - 'suite': 'dromaeojs-e10s', 22:59:14 INFO - 'system_bits': '32', 22:59:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:59:14 INFO - 'title': 'talos-linux64-ix-028', 22:59:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:59:14 INFO - 'use_talos_json': True, 22:59:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:59:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:59:14 INFO - 'work_dir': 'build'} 22:59:14 INFO - ##### 22:59:14 INFO - ##### Running clobber step. 22:59:14 INFO - ##### 22:59:14 INFO - Running pre-action listener: _resource_record_pre_action 22:59:14 INFO - Running main action method: clobber 22:59:14 INFO - rmtree: /builds/slave/test/build 22:59:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:59:15 INFO - Running post-action listener: _resource_record_post_action 22:59:15 INFO - ##### 22:59:15 INFO - ##### Running read-buildbot-config step. 22:59:15 INFO - ##### 22:59:15 INFO - Running pre-action listener: _resource_record_pre_action 22:59:15 INFO - Running main action method: read_buildbot_config 22:59:15 INFO - Using buildbot properties: 22:59:15 INFO - { 22:59:15 INFO - "project": "", 22:59:15 INFO - "product": "firefox", 22:59:15 INFO - "script_repo_revision": "production", 22:59:15 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs-e10s", 22:59:15 INFO - "repository": "", 22:59:15 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs-e10s", 22:59:15 INFO - "buildid": "20160421203400", 22:59:15 INFO - "pgo_build": "True", 22:59:15 INFO - "basedir": "/builds/slave/test", 22:59:15 INFO - "buildnumber": 675, 22:59:15 INFO - "slavename": "talos-linux64-ix-028", 22:59:15 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:59:15 INFO - "platform": "ubuntu64_hw", 22:59:15 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:59:15 INFO - "branch": "fx-team", 22:59:15 INFO - "repo_path": "integration/fx-team", 22:59:15 INFO - "revision": "4fa5909cf1e73939ee823442f2c69b0a86474489", 22:59:15 INFO - "stage_platform": "linux64-pgo", 22:59:15 INFO - "builduid": "2d653f6fc86e4b8e9726dd2d92e81848", 22:59:15 INFO - "slavebuilddir": "test" 22:59:15 INFO - } 22:59:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 22:59:15 INFO - Running post-action listener: _resource_record_post_action 22:59:15 INFO - ##### 22:59:15 INFO - ##### Running download-and-extract step. 22:59:15 INFO - ##### 22:59:15 INFO - Running pre-action listener: _resource_record_pre_action 22:59:15 INFO - Running main action method: download_and_extract 22:59:15 INFO - mkdir: /builds/slave/test/build/tests 22:59: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')]} 22:59:15 INFO - https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:59:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:59:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:59:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:59:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:59:20 INFO - Downloaded 1361 bytes. 22:59:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:59:20 INFO - Using the following test package requirements: 22:59:20 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:20 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:59:20 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:59:20 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'jsshell-linux-x86_64.zip'], 22:59:20 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:59:20 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:59:20 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:59:20 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:59:20 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:59:20 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:59:20 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:59:20 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:20 INFO - https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:59:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:59:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:59:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:59:21 INFO - Downloaded 23853527 bytes. 22:59:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:59:23 INFO - Return code: 0 22:59:23 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:23 INFO - https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:59:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:59:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:59:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:59:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:59:24 INFO - Downloaded 23853527 bytes. 22:59:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:59:26 INFO - Return code: 0 22:59:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:26 INFO - https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:59:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:59:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:59:27 INFO - Downloaded 10845952 bytes. 22:59:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:59:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:59:27 INFO - Return code: 0 22:59:27 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')]} 22:59:27 INFO - https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:59:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:59:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:59:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:59:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:59:29 INFO - Downloaded 58322983 bytes. 22:59:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:59:29 INFO - mkdir: /builds/slave/test/properties 22:59:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:59:29 INFO - Writing to file /builds/slave/test/properties/build_url 22:59:29 INFO - Contents: 22:59:29 INFO - build_url:https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:59:30 INFO - Running post-action listener: _resource_record_post_action 22:59:30 INFO - Running post-action listener: set_extra_try_arguments 22:59:30 INFO - ##### 22:59:30 INFO - ##### Running populate-webroot step. 22:59:30 INFO - ##### 22:59:30 INFO - Running pre-action listener: _resource_record_pre_action 22:59:30 INFO - Running main action method: populate_webroot 22:59:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:59:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:59:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:59:30 INFO - '--tppagecycles', 22:59:30 INFO - '7'], 22:59:30 INFO - 'tests': ['tsvgm']}}, 22:59:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:59:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:59:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:59:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:59:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:59:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['damp', 'tps']}, 22:59:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'tests': ['damp', 'tps']}, 22:59:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['dromaeo_dom']}, 22:59:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:59:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['a11yr', 22:59:30 INFO - 'ts_paint', 22:59:30 INFO - 'tpaint', 22:59:30 INFO - 'sessionrestore', 22:59:30 INFO - 'sessionrestore_no_auto_restore', 22:59:30 INFO - 'tabpaint']}, 22:59:30 INFO - 'other-e10s': {'tests': ['a11yr', 22:59:30 INFO - 'ts_paint', 22:59:30 INFO - 'tpaint', 22:59:30 INFO - 'sessionrestore', 22:59:30 INFO - 'sessionrestore_no_auto_restore', 22:59:30 INFO - 'tabpaint']}, 22:59:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:59:30 INFO - 'ts_paint', 22:59:30 INFO - 'tpaint', 22:59:30 INFO - 'sessionrestore', 22:59:30 INFO - 'sessionrestore_no_auto_restore', 22:59:30 INFO - 'tabpaint']}, 22:59:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:59:30 INFO - 'ts_paint', 22:59:30 INFO - 'tpaint', 22:59:30 INFO - 'sessionrestore', 22:59:30 INFO - 'sessionrestore_no_auto_restore', 22:59:30 INFO - 'tabpaint']}, 22:59:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['a11yr', 22:59:30 INFO - 'ts_paint', 22:59:30 INFO - 'tpaint', 22:59:30 INFO - 'sessionrestore', 22:59:30 INFO - 'sessionrestore_no_auto_restore', 22:59:30 INFO - 'tabpaint']}, 22:59:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['a11yr', 22:59:30 INFO - 'ts_paint', 22:59:30 INFO - 'tpaint', 22:59:30 INFO - 'sessionrestore', 22:59:30 INFO - 'sessionrestore_no_auto_restore', 22:59:30 INFO - 'tabpaint']}, 22:59:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['tsvgx', 22:59:30 INFO - 'tsvgr_opacity', 22:59:30 INFO - 'tart', 22:59:30 INFO - 'tscrollx', 22:59:30 INFO - 'cart']}, 22:59:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:59:30 INFO - 'tsvgr_opacity', 22:59:30 INFO - 'tart', 22:59:30 INFO - 'tscrollx', 22:59:30 INFO - 'cart']}, 22:59:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'talos_options': ['--disable-e10s'], 22:59:30 INFO - 'tests': ['tp5o']}, 22:59:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'tests': ['tp5o']}, 22:59:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'talos_options': ['--disable-e10s', 22:59:30 INFO - '--xperf_path', 22:59:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:30 INFO - 'tests': ['tp5n']}, 22:59:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:59:30 INFO - 'talos_options': ['--xperf_path', 22:59:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:59:30 INFO - 'tests': ['tp5n']}}, 22:59:30 INFO - 'talos.zip': {'path': '', 22:59:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:59:30 INFO - Running post-action listener: _resource_record_post_action 22:59:30 INFO - ##### 22:59:30 INFO - ##### Running create-virtualenv step. 22:59:30 INFO - ##### 22:59:30 INFO - Running pre-action listener: _resource_record_pre_action 22:59:30 INFO - Running main action method: create_virtualenv 22:59:30 INFO - Creating virtualenv /builds/slave/test/build/venv 22:59:30 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:59:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:59:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:59:30 INFO - Using real prefix '/usr' 22:59:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:59:30 INFO - Installing distribute.............................................................................................................................................................................................done. 22:59:32 INFO - Installing pip.................done. 22:59:32 INFO - Return code: 0 22:59:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:59:32 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')]} 22:59:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:32 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')]} 22:59:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:32 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 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:32 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 22:59:32 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 22:59:32 INFO - Using env: {'DISPLAY': ':0', 22:59:32 INFO - 'HOME': '/home/cltbld', 22:59:32 INFO - 'LANG': 'en_US.UTF-8', 22:59:32 INFO - 'LANGUAGE': 'en_US:en', 22:59:32 INFO - 'LOGNAME': 'cltbld', 22:59:32 INFO - 'MAIL': '/var/mail/cltbld', 22:59:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:32 INFO - 'MOZ_NO_REMOTE': '1', 22:59:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:32 INFO - 'NO_EM_RESTART': '1', 22:59:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:32 INFO - 'PWD': '/builds/slave/test', 22:59:32 INFO - 'SHELL': '/bin/bash', 22:59:32 INFO - 'SHLVL': '1', 22:59:32 INFO - 'TERM': 'linux', 22:59:32 INFO - 'TMOUT': '86400', 22:59:32 INFO - 'USER': 'cltbld', 22:59:32 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:32 INFO - '_': '/tools/buildbot/bin/python'} 22:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:33 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 22:59:33 INFO - Cleaning up... 22:59:33 INFO - Return code: 0 22:59:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:59:33 INFO - Using env: {'DISPLAY': ':0', 22:59:33 INFO - 'HOME': '/home/cltbld', 22:59:33 INFO - 'LANG': 'en_US.UTF-8', 22:59:33 INFO - 'LANGUAGE': 'en_US:en', 22:59:33 INFO - 'LOGNAME': 'cltbld', 22:59:33 INFO - 'MAIL': '/var/mail/cltbld', 22:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:33 INFO - 'MOZ_NO_REMOTE': '1', 22:59:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:33 INFO - 'NO_EM_RESTART': '1', 22:59:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:33 INFO - 'PWD': '/builds/slave/test', 22:59:33 INFO - 'SHELL': '/bin/bash', 22:59:33 INFO - 'SHLVL': '1', 22:59:33 INFO - 'TERM': 'linux', 22:59:33 INFO - 'TMOUT': '86400', 22:59:33 INFO - 'USER': 'cltbld', 22:59:33 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:33 INFO - '_': '/tools/buildbot/bin/python'} 22:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:33 INFO - Downloading/unpacking psutil>=0.7.1 22:59:33 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 22:59:33 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 22:59:33 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 22:59:33 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 22:59:33 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 22:59:33 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 22:59:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:59:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:35 INFO - Installing collected packages: psutil 22:59:35 INFO - Running setup.py install for psutil 22:59:36 INFO - building 'psutil._psutil_linux' extension 22:59:36 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 22:59:36 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 22:59:36 INFO - building 'psutil._psutil_posix' extension 22:59:36 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 22:59:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:59:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:59:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:59:37 INFO - Successfully installed psutil 22:59:37 INFO - Cleaning up... 22:59:37 INFO - Return code: 0 22:59:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:59:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:59:37 INFO - Using env: {'DISPLAY': ':0', 22:59:37 INFO - 'HOME': '/home/cltbld', 22:59:37 INFO - 'LANG': 'en_US.UTF-8', 22:59:37 INFO - 'LANGUAGE': 'en_US:en', 22:59:37 INFO - 'LOGNAME': 'cltbld', 22:59:37 INFO - 'MAIL': '/var/mail/cltbld', 22:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:37 INFO - 'MOZ_NO_REMOTE': '1', 22:59:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:37 INFO - 'NO_EM_RESTART': '1', 22:59:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:37 INFO - 'PWD': '/builds/slave/test', 22:59:37 INFO - 'SHELL': '/bin/bash', 22:59:37 INFO - 'SHLVL': '1', 22:59:37 INFO - 'TERM': 'linux', 22:59:37 INFO - 'TMOUT': '86400', 22:59:37 INFO - 'USER': 'cltbld', 22:59:37 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:37 INFO - '_': '/tools/buildbot/bin/python'} 22:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:39 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:59:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:59:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:59:39 INFO - Installing collected packages: mozsystemmonitor 22:59:39 INFO - Running setup.py install for mozsystemmonitor 22:59:39 INFO - Successfully installed mozsystemmonitor 22:59:39 INFO - Cleaning up... 22:59:39 INFO - Return code: 0 22:59:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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', '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 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:59: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 blobuploader==1.2.4 22:59:39 INFO - Using env: {'DISPLAY': ':0', 22:59:39 INFO - 'HOME': '/home/cltbld', 22:59:39 INFO - 'LANG': 'en_US.UTF-8', 22:59:39 INFO - 'LANGUAGE': 'en_US:en', 22:59:39 INFO - 'LOGNAME': 'cltbld', 22:59:39 INFO - 'MAIL': '/var/mail/cltbld', 22:59:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:39 INFO - 'MOZ_NO_REMOTE': '1', 22:59:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:39 INFO - 'NO_EM_RESTART': '1', 22:59:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:39 INFO - 'PWD': '/builds/slave/test', 22:59:39 INFO - 'SHELL': '/bin/bash', 22:59:39 INFO - 'SHLVL': '1', 22:59:39 INFO - 'TERM': 'linux', 22:59:39 INFO - 'TMOUT': '86400', 22:59:39 INFO - 'USER': 'cltbld', 22:59:39 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:39 INFO - '_': '/tools/buildbot/bin/python'} 22:59:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:39 INFO - Downloading/unpacking blobuploader==1.2.4 22:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:41 INFO - Downloading blobuploader-1.2.4.tar.gz 22:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:59:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:59:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:42 INFO - Downloading docopt-0.6.1.tar.gz 22:59:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:59:42 INFO - Installing collected packages: blobuploader, requests, docopt 22:59:42 INFO - Running setup.py install for blobuploader 22:59:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:59:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:59:42 INFO - Running setup.py install for requests 22:59:42 INFO - Running setup.py install for docopt 22:59:42 INFO - Successfully installed blobuploader requests docopt 22:59:42 INFO - Cleaning up... 22:59:42 INFO - Return code: 0 22:59:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59: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')]} 22:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59: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')]} 22:59:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:42 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 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:42 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 22:59:42 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 22:59:42 INFO - Using env: {'DISPLAY': ':0', 22:59:42 INFO - 'HOME': '/home/cltbld', 22:59:42 INFO - 'LANG': 'en_US.UTF-8', 22:59:42 INFO - 'LANGUAGE': 'en_US:en', 22:59:42 INFO - 'LOGNAME': 'cltbld', 22:59:42 INFO - 'MAIL': '/var/mail/cltbld', 22:59:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:42 INFO - 'MOZ_NO_REMOTE': '1', 22:59:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:42 INFO - 'NO_EM_RESTART': '1', 22:59:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:42 INFO - 'PWD': '/builds/slave/test', 22:59:42 INFO - 'SHELL': '/bin/bash', 22:59:42 INFO - 'SHLVL': '1', 22:59:42 INFO - 'TERM': 'linux', 22:59:42 INFO - 'TMOUT': '86400', 22:59:42 INFO - 'USER': 'cltbld', 22:59:42 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:42 INFO - '_': '/tools/buildbot/bin/python'} 22:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:43 INFO - Running setup.py (path:/tmp/pip-Jph82K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:43 INFO - Running setup.py (path:/tmp/pip-hdSuAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:43 INFO - Running setup.py (path:/tmp/pip-etN301-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:43 INFO - Running setup.py (path:/tmp/pip-VuPUGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:43 INFO - Running setup.py (path:/tmp/pip-ULFoqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:43 INFO - Running setup.py (path:/tmp/pip-APQGXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:43 INFO - Running setup.py (path:/tmp/pip-LAUxzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:43 INFO - Running setup.py (path:/tmp/pip-5Kd5x2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:43 INFO - Running setup.py (path:/tmp/pip-anTCK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:43 INFO - Running setup.py (path:/tmp/pip-PJeVJu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:43 INFO - Running setup.py (path:/tmp/pip-M73ihJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:43 INFO - Running setup.py (path:/tmp/pip-trzMoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:44 INFO - Running setup.py (path:/tmp/pip-X982pG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:44 INFO - Running setup.py (path:/tmp/pip-JZUrE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:44 INFO - Running setup.py (path:/tmp/pip-mav5sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:44 INFO - Running setup.py (path:/tmp/pip-UKLmtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:44 INFO - Running setup.py (path:/tmp/pip-9Q1Maf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:59:44 INFO - Running setup.py install for manifestparser 22:59:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:59:44 INFO - Running setup.py install for mozcrash 22:59:44 INFO - Running setup.py install for mozdebug 22:59:44 INFO - Running setup.py install for mozdevice 22:59:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:59:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:59:44 INFO - Running setup.py install for mozfile 22:59:44 INFO - Running setup.py install for mozhttpd 22:59:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:59:44 INFO - Running setup.py install for mozinfo 22:59:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Running setup.py install for mozInstall 22:59:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Running setup.py install for mozleak 22:59:45 INFO - Running setup.py install for mozlog 22:59:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Running setup.py install for moznetwork 22:59:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Running setup.py install for mozprocess 22:59:45 INFO - Running setup.py install for mozprofile 22:59:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Running setup.py install for mozrunner 22:59:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:59:45 INFO - Running setup.py install for mozscreenshot 22:59:45 INFO - Running setup.py install for moztest 22:59:46 INFO - Running setup.py install for mozversion 22:59:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:59:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:59:46 INFO - Cleaning up... 22:59:46 INFO - Return code: 0 22:59:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:46 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')]} 22:59:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:46 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')]} 22:59:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:46 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 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:46 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 22:59:46 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 22:59:46 INFO - Using env: {'DISPLAY': ':0', 22:59:46 INFO - 'HOME': '/home/cltbld', 22:59:46 INFO - 'LANG': 'en_US.UTF-8', 22:59:46 INFO - 'LANGUAGE': 'en_US:en', 22:59:46 INFO - 'LOGNAME': 'cltbld', 22:59:46 INFO - 'MAIL': '/var/mail/cltbld', 22:59:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:46 INFO - 'MOZ_NO_REMOTE': '1', 22:59:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:46 INFO - 'NO_EM_RESTART': '1', 22:59:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:46 INFO - 'PWD': '/builds/slave/test', 22:59:46 INFO - 'SHELL': '/bin/bash', 22:59:46 INFO - 'SHLVL': '1', 22:59:46 INFO - 'TERM': 'linux', 22:59:46 INFO - 'TMOUT': '86400', 22:59:46 INFO - 'USER': 'cltbld', 22:59:46 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:46 INFO - '_': '/tools/buildbot/bin/python'} 22:59:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:59:46 INFO - Running setup.py (path:/tmp/pip-UpePp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:59:46 INFO - Running setup.py (path:/tmp/pip-4yLhIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:59:46 INFO - Running setup.py (path:/tmp/pip-FwP8un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:59:46 INFO - Running setup.py (path:/tmp/pip-tRXzSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:59:46 INFO - Running setup.py (path:/tmp/pip-Mp0_I5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:59:46 INFO - Running setup.py (path:/tmp/pip-vtT3Yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:59:46 INFO - Running setup.py (path:/tmp/pip-WS0_tQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:59:46 INFO - Running setup.py (path:/tmp/pip-OX2j5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:59:46 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)) 22:59:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:59:46 INFO - Running setup.py (path:/tmp/pip-Md_IFF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:59:47 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:59:47 INFO - Running setup.py (path:/tmp/pip-RumRvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:59:47 INFO - Running setup.py (path:/tmp/pip-vgASi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:59:47 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:59:47 INFO - Running setup.py (path:/tmp/pip-I0gXtj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:59:47 INFO - Running setup.py (path:/tmp/pip-jfQqLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:59:47 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:59:47 INFO - Running setup.py (path:/tmp/pip-U2eZtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:59:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:47 INFO - Running setup.py (path:/tmp/pip-AZbyby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:59:47 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:59:47 INFO - Running setup.py (path:/tmp/pip-pBZmaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:59:47 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)) 22:59:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:59:47 INFO - Running setup.py (path:/tmp/pip-Bl19E9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:59:47 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)) 22:59:47 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)) 22:59:47 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)) 22:59:47 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)) 22:59:47 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)) 22:59:47 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)) 22:59:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:49 INFO - Downloading blessings-1.6.tar.gz 22:59:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:59:49 INFO - Installing collected packages: blessings 22:59:49 INFO - Running setup.py install for blessings 22:59:49 INFO - Successfully installed blessings 22:59:49 INFO - Cleaning up... 22:59:49 INFO - Return code: 0 22:59:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:59:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:59:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:59:49 INFO - Reading from file tmpfile_stdout 22:59:49 INFO - Current package versions: 22:59:49 INFO - argparse == 1.2.1 22:59:49 INFO - blessings == 1.6 22:59:49 INFO - blobuploader == 1.2.4 22:59:49 INFO - docopt == 0.6.1 22:59:49 INFO - manifestparser == 1.1 22:59:49 INFO - mozInstall == 1.12 22:59:49 INFO - mozcrash == 0.17 22:59:49 INFO - mozdebug == 0.1 22:59:49 INFO - mozdevice == 0.48 22:59:49 INFO - mozfile == 1.2 22:59:49 INFO - mozhttpd == 0.7 22:59:49 INFO - mozinfo == 0.9 22:59:49 INFO - mozleak == 0.1 22:59:49 INFO - mozlog == 3.1 22:59:49 INFO - moznetwork == 0.27 22:59:49 INFO - mozprocess == 0.22 22:59:49 INFO - mozprofile == 0.28 22:59:49 INFO - mozrunner == 6.11 22:59:49 INFO - mozscreenshot == 0.1 22:59:49 INFO - mozsystemmonitor == 0.0 22:59:49 INFO - moztest == 0.7 22:59:49 INFO - mozversion == 1.4 22:59:49 INFO - psutil == 3.1.1 22:59:49 INFO - requests == 1.2.3 22:59:49 INFO - wsgiref == 0.1.2 22:59:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:49 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 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:49 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 22:59:49 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 22:59:49 INFO - Using env: {'DISPLAY': ':0', 22:59:49 INFO - 'HOME': '/home/cltbld', 22:59:49 INFO - 'LANG': 'en_US.UTF-8', 22:59:49 INFO - 'LANGUAGE': 'en_US:en', 22:59:49 INFO - 'LOGNAME': 'cltbld', 22:59:49 INFO - 'MAIL': '/var/mail/cltbld', 22:59:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:49 INFO - 'MOZ_NO_REMOTE': '1', 22:59:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:49 INFO - 'NO_EM_RESTART': '1', 22:59:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:49 INFO - 'PWD': '/builds/slave/test', 22:59:49 INFO - 'SHELL': '/bin/bash', 22:59:49 INFO - 'SHLVL': '1', 22:59:49 INFO - 'TERM': 'linux', 22:59:49 INFO - 'TMOUT': '86400', 22:59:49 INFO - 'USER': 'cltbld', 22:59:49 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:49 INFO - '_': '/tools/buildbot/bin/python'} 22:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 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)) 22:59:50 INFO - Cleaning up... 22:59:50 INFO - Return code: 0 22:59:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:50 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 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:50 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 22:59:50 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 22:59:50 INFO - Using env: {'DISPLAY': ':0', 22:59:50 INFO - 'HOME': '/home/cltbld', 22:59:50 INFO - 'LANG': 'en_US.UTF-8', 22:59:50 INFO - 'LANGUAGE': 'en_US:en', 22:59:50 INFO - 'LOGNAME': 'cltbld', 22:59:50 INFO - 'MAIL': '/var/mail/cltbld', 22:59:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:50 INFO - 'MOZ_NO_REMOTE': '1', 22:59:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:50 INFO - 'NO_EM_RESTART': '1', 22:59:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:50 INFO - 'PWD': '/builds/slave/test', 22:59:50 INFO - 'SHELL': '/bin/bash', 22:59:50 INFO - 'SHLVL': '1', 22:59:50 INFO - 'TERM': 'linux', 22:59:50 INFO - 'TMOUT': '86400', 22:59:50 INFO - 'USER': 'cltbld', 22:59:50 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:50 INFO - '_': '/tools/buildbot/bin/python'} 22:59:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:50 INFO - Downloading/unpacking jsonschema 22:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:59:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:59:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:59:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:59:52 INFO - Downloading/unpacking functools32 (from jsonschema) 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:52 INFO - Downloading functools32-3.2.3-2.tar.gz 22:59:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:59:52 INFO - warning: no files found matching '*.txt' 22:59:52 INFO - no previously-included directories found matching 'build' 22:59:52 INFO - no previously-included directories found matching 'dist' 22:59:52 INFO - no previously-included directories found matching '.git*' 22:59:52 INFO - Installing collected packages: jsonschema, functools32 22:59:52 INFO - Running setup.py install for functools32 22:59:52 INFO - warning: no files found matching '*.txt' 22:59:52 INFO - no previously-included directories found matching 'build' 22:59:52 INFO - no previously-included directories found matching 'dist' 22:59:52 INFO - no previously-included directories found matching '.git*' 22:59:52 INFO - Successfully installed jsonschema functools32 22:59:52 INFO - Cleaning up... 22:59:52 INFO - Return code: 0 22:59:52 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:59:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:59:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:59:52 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6f8066f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1c718a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1d3c5a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, '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': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', '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 22:59:52 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', 'flake8'] in /builds/slave/test/build 22:59:52 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 flake8 22:59:52 INFO - Using env: {'DISPLAY': ':0', 22:59:52 INFO - 'HOME': '/home/cltbld', 22:59:52 INFO - 'LANG': 'en_US.UTF-8', 22:59:52 INFO - 'LANGUAGE': 'en_US:en', 22:59:52 INFO - 'LOGNAME': 'cltbld', 22:59:52 INFO - 'MAIL': '/var/mail/cltbld', 22:59:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:52 INFO - 'MOZ_NO_REMOTE': '1', 22:59:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:52 INFO - 'NO_EM_RESTART': '1', 22:59:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:52 INFO - 'PWD': '/builds/slave/test', 22:59:52 INFO - 'SHELL': '/bin/bash', 22:59:52 INFO - 'SHLVL': '1', 22:59:52 INFO - 'TERM': 'linux', 22:59:52 INFO - 'TMOUT': '86400', 22:59:52 INFO - 'USER': 'cltbld', 22:59:52 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 22:59:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:52 INFO - '_': '/tools/buildbot/bin/python'} 22:59:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:59:52 INFO - Downloading/unpacking flake8 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59: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 22:59:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:59:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:59:54 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 22:59:54 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 22:59:54 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 22:59:54 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 22:59:54 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 22:59:54 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 22:59:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:59:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 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 22:59:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:59:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:59:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:59:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:59:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:59:55 INFO - no previously-included directories found matching 'docs/_build' 22:59:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:59:55 INFO - Running setup.py install for pep8 22:59:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:59:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:59:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:59:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:59:55 INFO - no previously-included directories found matching 'docs/_build' 22:59:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:59:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:59:55 INFO - Cleaning up... 22:59:55 INFO - Return code: 0 22:59:55 INFO - Running post-action listener: _resource_record_post_action 22:59:55 INFO - Running post-action listener: _start_resource_monitoring 22:59:55 INFO - Starting resource monitoring. 22:59:55 INFO - ##### 22:59:55 INFO - ##### Running install step. 22:59:55 INFO - ##### 22:59:55 INFO - Running pre-action listener: _resource_record_pre_action 22:59:55 INFO - Running main action method: install 22:59:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:59:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:59:56 INFO - Reading from file tmpfile_stdout 22:59:56 INFO - Detecting whether we're running mozinstall >=1.0... 22:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:59:56 INFO - Reading from file tmpfile_stdout 22:59:56 INFO - Output received: 22:59:56 INFO - Usage: mozinstall [options] installer 22:59:56 INFO - Options: 22:59:56 INFO - -h, --help show this help message and exit 22:59:56 INFO - -d DEST, --destination=DEST 22:59:56 INFO - Directory to install application into. [default: 22:59:56 INFO - "/builds/slave/test"] 22:59:56 INFO - --app=APP Application being installed. [default: firefox] 22:59:56 INFO - mkdir: /builds/slave/test/build/application 22:59:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:59:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 23:00:12 INFO - Reading from file tmpfile_stdout 23:00:12 INFO - Output received: 23:00:12 INFO - /builds/slave/test/build/application/firefox/firefox 23:00:12 INFO - Running post-action listener: _resource_record_post_action 23:00:12 INFO - ##### 23:00:12 INFO - ##### Running run-tests step. 23:00:12 INFO - ##### 23:00:12 INFO - Running pre-action listener: _resource_record_pre_action 23:00:12 INFO - Running main action method: run_tests 23:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:00:12 INFO - Python 2.7.3 23:00:12 INFO - Return code: 0 23:00:12 INFO - grabbing minidump binary from tooltool 23:00: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')]} 23:00: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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1dcb410>, '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 0x1dcc310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1dcc7a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:00: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 23:00: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 23:00:12 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:00:12 INFO - Return code: 0 23:00:12 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 23:00:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:00:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:00:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 23:00:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:00:13 INFO - Return code: 0 23:00:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 23:00:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-028 --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 23:00:13 INFO - Using env: {'DISPLAY': ':0', 23:00:13 INFO - 'HOME': '/home/cltbld', 23:00:13 INFO - 'LANG': 'en_US.UTF-8', 23:00:13 INFO - 'LANGUAGE': 'en_US:en', 23:00:13 INFO - 'LOGNAME': 'cltbld', 23:00:13 INFO - 'MAIL': '/var/mail/cltbld', 23:00:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:00:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 23:00:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:00:13 INFO - 'MOZ_NO_REMOTE': '1', 23:00:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:00:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 23:00:13 INFO - 'NO_EM_RESTART': '1', 23:00:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 23:00:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:00:13 INFO - 'PWD': '/builds/slave/test', 23:00:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:00:13 INFO - 'SHELL': '/bin/bash', 23:00:13 INFO - 'SHLVL': '1', 23:00:13 INFO - 'TERM': 'linux', 23:00:13 INFO - 'TMOUT': '86400', 23:00:13 INFO - 'USER': 'cltbld', 23:00:13 INFO - 'XDG_SESSION_COOKIE': '836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848', 23:00:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:00:13 INFO - '_': '/tools/buildbot/bin/python'} 23:00:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-028', '--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 23:00:13 INFO - mozversion application_buildid: 20160421203400 23:00:13 INFO - mozversion application_changeset: 4fa5909cf1e73939ee823442f2c69b0a86474489 23:00:13 INFO - mozversion application_display_name: Nightly 23:00:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:00:13 INFO - mozversion application_name: Firefox 23:00:13 INFO - mozversion application_remotingname: firefox 23:00:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 23:00:13 INFO - mozversion application_vendor: Mozilla 23:00:13 INFO - mozversion application_version: 48.0a1 23:00:13 INFO - mozversion platform_buildid: 20160421203400 23:00:13 INFO - mozversion platform_changeset: 4fa5909cf1e73939ee823442f2c69b0a86474489 23:00:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 23:00:13 INFO - mozversion platform_version: 48.0a1 23:00:13 INFO - using testdate: 1461304813 23:00:13 INFO - actual date: 1461304813 23:00:13 INFO - starting webserver on 'localhost:54028' 23:00:13 INFO - SUITE-START | Running 2 tests 23:00:13 INFO - TEST-START | dromaeo_css 23:00:13 INFO - operating with platform_type : linux_ 23:00:13 INFO - Initialising browser for dromaeo_css test... 23:00:13 INFO - TEST-INFO | started process 7145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyjTwUX/profile http://localhost:54028/getInfo.html) 23:00:18 INFO - PROCESS | 7145 | 23:00:18 INFO - PROCESS | 7145 | (process:7214): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:00:18 INFO - PROCESS | 7145 | __metrics Screen width/height:1600/1200 23:00:18 INFO - PROCESS | 7145 | colorDepth:24 23:00:18 INFO - PROCESS | 7145 | Browser inner width/height: 1024/697 23:00:18 INFO - PROCESS | 7145 | __metrics 23:00:19 ERROR - PROCESS | 7145 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 23:00:20 INFO - TEST-INFO | 7145: exit 0 23:00:20 INFO - Browser initialized. 23:00:20 INFO - Running cycle 1/1 for dromaeo_css test... 23:00:20 INFO - TEST-INFO | started process 7265 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyjTwUX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:00:22 INFO - PROCESS | 7265 | 23:00:22 INFO - PROCESS | 7265 | (process:7327): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:00:32 INFO - PROCESS | 7265 | RSS: Main: 163848192 23:00:32 INFO - PROCESS | 7265 | 23:01:21 INFO - PROCESS | 7265 | 1461304881860 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] 23:03:30 INFO - PROCESS | 7265 | Cycle 1(1): loaded http://localhost:54028/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54028/tests/dromaeo/cssquery-ext.html) 23:03:31 INFO - PROCESS | 7265 | RSS: Main: 167231488 23:03:31 INFO - PROCESS | 7265 | 23:06:32 INFO - PROCESS | 7265 | Cycle 1(1): loaded http://localhost:54028/tests/dromaeo/cssquery-ext.html (next: http://localhost:54028/tests/dromaeo/cssquery-jquery.html) 23:06:33 INFO - PROCESS | 7265 | RSS: Main: 166174720 23:06:33 INFO - PROCESS | 7265 | 23:09:35 INFO - PROCESS | 7265 | Cycle 1(1): loaded http://localhost:54028/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54028/tests/dromaeo/cssquery-mootools.html) 23:09:35 INFO - PROCESS | 7265 | RSS: Main: 166928384 23:09:35 INFO - PROCESS | 7265 | 23:12:37 INFO - PROCESS | 7265 | Cycle 1(1): loaded http://localhost:54028/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54028/tests/dromaeo/cssquery-prototype.html) 23:12:38 INFO - PROCESS | 7265 | RSS: Main: 164765696 23:12:38 INFO - PROCESS | 7265 | 23:15:40 INFO - PROCESS | 7265 | Cycle 1(1): loaded http://localhost:54028/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54028/tests/dromaeo/cssquery-yui.html) 23:15:41 INFO - PROCESS | 7265 | RSS: Main: 166211584 23:15:41 INFO - PROCESS | 7265 | 23:18:43 INFO - PROCESS | 7265 | Cycle 1(1): loaded http://localhost:54028/tests/dromaeo/cssquery-yui.html (next: http://localhost:54028/tests/dromaeo/cssquery-dojo.html) 23:18:44 INFO - PROCESS | 7265 | RSS: Main: 167841792 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | __start_tp_report 23:18:44 INFO - PROCESS | 7265 | _x_x_mozilla_page_load 23:18:44 INFO - PROCESS | 7265 | _x_x_mozilla_page_load_details 23:18:44 INFO - PROCESS | 7265 | |i|pagename|runs| 23:18:44 INFO - PROCESS | 7265 | |0;dojo.html;3197.4404479216137;3205.759568032398;3227.9515807262896;3254.707076363127;3272.754543409245;5448.91826622601;5460.262864513289;5479.589030822694;5491.203775452562;5511.145772405283;5401.405845357008;5456.263404440399;5551.139573366135;5626.015447296727;5638.718064096799;5009.549140577347;5032.521910418504;5091.312672789181;5093.337866077405;5153.304303918961;567.4025805996306;4998.650094493387;5056.367954005749;5113.105206588851;5138.665986710852;5107.438181799999;5137.6660517066275;5140.434552199236;5151.74241287936;5162.1224391853475;5144.459831717691;5147.356580427447;5149.665271757323;5157.767900444489;5205.141151710001;4958.479359667219;5074.441811400743;5129.256257842592;5130.3074084998425;5174.3790745110455;5514.200440936082;5529.474699903502;5530.059889818741;5546.667199968015;5563.276774019392;5754.798582049608;6014.458698717136;6018.428249316308;6029.397060293979;6041.426064523863;5891.852703682443;5943.3462319144855;5952.1964534787685;5960.225170727777;5968.731407086691;5529.917051244234;5531.529819965268;5534.391216966131;5535.806246781383;5544.667319960815;5824.097264923944;6031.155638210654;6033.185519954838;6035.939640603538;6036.124761909587;5914.4381283777975;5917.467427931507;5917.733701983421;5918.23063001807;5970.611910225822;5996.370381110008;6004.279486461653;6007.639541627429;6008.158857759917;6012.278526576839;5776.480116789425;5912.793052243151;5920.970395147997;5966.463018328284;5979.641221526722;5847.912281315784;5878.088896221093;5904.645721256738;5913.46778789911;5965.105234214816;4462.620677242406;4462.620677242471;4467.24056910326;4471.061077173765;4507.5943165115295;3824.2733880562605;3979.46277252573;4000.3399439092805;4010.8796736097966;4054.2094291613444;5391.946080539223;5649.915251271234;5652.0391533439415;5668.206451096849;5760.942390576124;5988.251468566429;5988.880222395528;5991.55063370249;6031.004884194146;6049.364816694272;5379.892402151935;5500.1749737538885;5575.665460072409;5617.354004289477;5678.4605462480995;5124.5131712487255;5132.5124113209185;5137.049645815536;5145.305383773143;5179.8446046618665;6148.262208535004;6178.227721534809;6212.06818977148;6219.409156130251;6231.688415579203;5766.106253530709;5812.564057695605;6061.939380606137;6064.818055458343;6073.544484163794;6158.538109641884;6191.040388739752;6199.318075011835;6199.5040396767245;6201.069839524108;5895.469407753323;5917.644941303535;5932.0508718604815;5932.911006334994;5966.105084237399;5907.527397808251;5985.790497332572;6004.489618382487;6067.180930574493;6078.300995385587;5797.391273916347;5813.79651712188;5816.82549523515;5830.795922142875;5872.236609240771;5785.247917770663;5795.8551036224435;5798.884022319615;5800.912986305125;5804.912926306024;5518.365387980433;5636.2954630671165;5759.9136012959;5821.621594596338;5839.824805255849;5842.678652674143;5859;5888.587798854039;5891.086881533369;5897;5769.74036168365;5773;5790.768369265183;5793.95708772425;5802.332731735735 23:18:44 INFO - PROCESS | 7265 | |1;ext.html;586.7564960541375;638.2723694987715;649.402549654318;650.3431534150508;660.9702563384648;37167.62832371679;37745;38121.80939095307;38203.42694859579;38210.04474888126;39819.40270895939;40560.391594126035;40619.796901015456;40642;40729.59270407295;8525.95737021314;8673.306135509176;8692.956535217316;8693.087225841258;8716.477011379306;8574.828503429928;8659.263962563158;8662;8852.601632926502;8869.423487473294;8870.73387798367;8936.41913275635;8987;9035.27717782579;9038.954805225965;8868.15752503511;8928.955355223216;9000.189982901571;9003.054679258681;9021.729348119567;8849.690260840904;8883.644654213824;8979.730608081769;8987.730368088967;9001.099890011012;41452.170956580696;41894.58105418937;42078.368824467354;42245.36631950523;42280.365794513105;3492.0746002309406;4102.610252026053;4286.164197981395;4286.8499602514075;4353.107612939371;31767;31917.202069948205;32505.67494325074;32556.511652325233;33472.33055338904;4367.45406824147;4412.227860124465;4450.421445212134;4480.910381792346;4572.588467037983;292409;292786;293728;294144;294569.0543094563;35889.28221435557;57600.71199644059;58476.12285815716;58499.41500585025;58531.70734146302;34936;35042;35166.472502912475;35271;35271.294574108375;15067.849321506865;15438.305276262596;15585.688286234212;15604.68790624204;15623.765643515357;12688.80966785499;12749.362531873368;12752.171108877894;12752.426140823685;12784.105112642026;8421.410501264974;8688.304935605136;8689.13108689126;8701.129887011248;8781.604827782765;3298.802071875695;3341.565596472443;3371.2077661749468;3389.796612203276;3397.76215664906;1073.3398959639765;1089.4171618184234;1099.4997276239292;1194.2297218294152;1232.186756740547;31538.526922096185;31607.84196079051;31709;31838.204044899063;32360.35279294401;1111.9444027798577;1172.8006238939402;1214.7084699672191;1217.5251651855783;1270.6378682075565;16378.508644740677;16630;17175.65648687044;17210.655786884192;17259.568510787332;449591;452762;458007;460111;461335;55917.7204113985;57340;57346.42653573411;57562.42437575654;57586.7120664394;1373.0937581196363;1386.81278027465;1401.88084012858;1403.417581703602;1415.6177831985306;30050;30549.54175687411;30957.535636965913;31538.684613153575;31713.365732685677;50542.74728626334;52034.21948670697;52054.479455204964;52059.73970130125;52094.47905520896;378448;382118;385120;385237;385819;28679;44822.551774483145;45434;45712.771436143936;45937;46244.76877615725;46589.76705116453;46916;47385.763071184425;47571.28643070287;39029;39547;39832.80083599564;40230.19539609133;40598.1880362397;39288.2142357157;39697.603023969394;40153;40310;40350.84214101658;36604.26791464209;40298.194036118526;41462;42203;42434;34381.31237375289;35251;35496.82251588725;36110.819445902605;36158.457623135146;27384;27942.161735147976;28049.439011219252;28336;28395.574066389432 23:18:44 INFO - PROCESS | 7265 | |2;jquery.html;402.949631296088;403.1533779063965;408.26104750401794;409.4227139732977;416.5522063781436;13638.181709097462;17496.212670429828;18196.363127290548;18258.452246432596;18324.725129123057;16934.915325423386;17186.140692965364;17595.296188152457;18467.168977396017;18534.351297704583;5612.242347283126;5674.09214525676;5781.39295373986;5816.040353341694;5871.823845284648;5721.199032135504;5735.397783232763;5767.36558978512;5818.912716309259;5833.620814647034;5551.9722401387935;5722.570807189478;5739.339975902762;5761.423857614247;5769.221155144044;5720.9713951430185;5737.196792449061;5740.138979153118;5770.653760774346;5778.566607504433;4891.926621100686;5074.3657042869645;5085.720285384303;5089.083964886319;5093.490650934914;21449.463763405885;21755.891220543956;22058.779412205833;22250.666240006412;22305.888470557704;2373.3473294843884;2442.584760590704;2458.754124587545;2538.4034751833487;2831.674357448899;13632.795508067387;14027.43890244389;14104;14368.906824356787;14519.927400363036;1975.595003024391;2017.3040301096098;2026.1186383922893;2037.953918055611;2039.377989713137;74922.62538687272;75953.24046759517;76849.99999999945;77094;77184.22815771883;10213.693589192337;10278.948605256926;10594.735131621617;10660.147188224923;10721.78556428867;14853.777193342108;14904;14945.103293802407;14945.925270373797;14972.727318178077;8147.348212143014;8364.58177091143;8373.874391884126;8415.747527574182;8419.410641255176;8022.1175670676175;8044.678212871537;8062;8203.917960820316;8233.300169485541;6386;6449.806505804833;6494.188226471691;6560.671966401661;6575.473962083021;1976.772671142837;1977.6539105656452;1993.700944858254;2000.4198782352955;2031.7561892572985;978.9363691360039;1119.9664010079712;1149.1611123879447;1155.9768804623861;1196.7606478704295;16995.065271409945;17795.932244065396;18137.818621813614;18217.544561385806;18807.529811754815;1001.1440218612981;1060.6817954613584;1075.381655548063;1080.68660088575;1081.1620993729923;9551.188149007274;9828.31201815866;9886.456244906456;9921.603135874628;9926.2059035277;472795;472981;482732;493803;497035;92792;93044.53477732703;94279;94398.5280073609;95116.52441737747;1112.8497652816766;1113.0817075912244;1118.139032944628;1122.2649164797072;1143.9199256052163;15787.579117879446;15832.683346332778;15907.761383579487;15988.920055399649;16043.518694439186;35770.28459430849;36134;36297.27405451823;36780;37907;52743.20885186802;53684;54935.45064549303;55731;58018.709906450196;8141.633626486941;8142.267195952392;8233.941514621345;8375.246227839523;8423.578821059045;8902.020777714575;11874.465649045986;12801.61595152147;12916.547920822726;13014.544490942772;10903.34579925207;11024.669259921895;11255.831162532731;11476.655700329004;11501;11159.10727141843;11268.549258029914;11290.49192786343;11299.322040677585;11353.489092991;11707.473163707484;11850.111241656738;11895.405229738306;11918.582849600223;12024.699382515508;8316.501009939422;8351.624176911931;8375.120612335617;8408.645579237824;8644.91355086441;11571.768564628828;11609.767804644029;11688.532458701897;11741.060715142597;11848.822267665819 23:18:44 INFO - PROCESS | 7265 | |3;mootools.html;1528.9694206115878;1552.0532475190134;1685.4522280258918;1777.9598934623245;1830.4966134313054;7165.820854478628;8264.62809173587;8401.327893768499;8585.570721463926;8598.742037738863;8274.710385136521;8300.83398332033;8349.332053435726;8349.666013359458;8386.245237928586;2368.5736567417816;2400.4238982644215;2403.67550380699;2404.4589967257316;2404.89177986991;2340.578695834749;2353.7646235376496;2382.0709923129994;2385.7852793248603;2391.414103544634;2274.4996100857784;2325.2326732178426;2332.4518738096535;2368.3368656776042;2373.22870067228;2342.7540108288727;2345.85924844509;2358.705161854768;2362.8109751219863;2366.7869891709656;2266.8073213776847;2298.321995011476;2301.516681496871;2314.3751187179537;2320.5474932388192;8473.61868715903;8576.056633770342;8579.442336248187;8580.39937204396;8582.828343433159;5037.244413337992;5139.871503212412;5202.817901373472;5207.062728708831;5218.556422704075;3451.5340429042008;3519.7008254298416;3571.178628915338;3607.5310209672575;3624.7281453891064;1048.958041678332;1068.6793961811425;1073.420353009374;1083.7236504691332;1089.6186334782842;11377.772444551145;11410.201285909907;11454.942725286404;11483.42582870853;11498.942505287503;4351.151525452569;4417.845375411909;4523.276275795857;4563.589276965089;4586.678932474695;9283.303752218611;9338.95330523343;9339.533023348806;9349.298802589832;9358.57886395114;5127.564157046693;5146.459621739738;5161.948380516147;5170.405403378584;5178.844634660966;4088.897777555585;4697.060587882437;4758.357621721093;4809.567138957511;4828.155072862235;3681.3005528949416;3683.1160521474653;3719.256148770257;3777.9244415111543;3784.508013958168;1171.824226366052;1175.2360965372404;1205.0901569315286;1210.7396909664465;1220.6277085489012;844.6030365728097;952.1525842000486;953.8092381523722;957.7414098193449;1000.7047920863363;5711.657300561979;5870.471657550841;5961.314448838352;6078.209832721718;6113.908291375633;1007.7581380468781;1032.912202462784;1084.2464487181492;1120.960766373173;1122.618309774664;4023.5574086850424;4056.8985775355854;4160.375943608483;4165.10450253197;4166.729162604421;10221.233407494468;10595.417252051056;10651.201159913038;10768.084712799344;10774.946125269324;10605.734856628646;10640.095591874622;10674.839877401846;10685.305455145392;10689.30519516229;7721.459497835098;7966.16355282687;8030.478018928751;8035.517868927883;8036.879446808302;5537.667739935617;5602.663840169603;5750.338711048283;5790.710464476843;5791.247137872218;8718.91281087181;8956.193942544942;8966.462012279284;8986.640534378814;9271.165595096474;9469.621215151317;9577.473238971923;9674.564644591152;9713.417194968324;9720.368176068532;6538.215414150332;7177.712891484491;7276.126864776261;7407.148177959388;7628.847423051397;8261.215184557457;8286.171382861665;8305.086440491661;8320.625571849161;8341.45780524264;7025;7200.279972002758;7222.74720384805;7323.04800375969;7386.741464048732;7395.630218488946;7411.518251313647;7420.962895185489;7455.254474552501;7546.886796698163;6980.546264492792;6997.300269973166;7026.578405295699;7067.399271062018;7076.25699301566;4960.319431063185;5061.569766569735;5103.285540024325;5174.7153906533995;5289.259503669412;5320.654157479751;5407.540359069366;5433.347998240237;5434.945650543602;5520.475554822286 23:18:44 INFO - PROCESS | 7265 | |4;prototype.html;324.0715350520759;327.3812494385611;329.91092405050637;338.12257192585236;340.3193612774451;6066.636001839893;6070.453659170674;6104.389561043893;6123.479504242139;6166.6300021998595;5981.58128930975;5983.640981541111;6009.4591486766185;6026.548008899339;6046.12331211974;4560.8859778505475;4571.674214477797;4638.907221855561;4653.813847446115;4686.835960741366;4613.400257966459;4629.907401851961;4641.955559999007;4658.953410465902;4662.3705799716945;4611.086399144351;4629.629629629621;4635;4639.6752227344095;4641.605463535603;4375.737455752649;4476.507584165755;4624.1445332613575;4645.837395691135;4649.628029757611;4038.62686686528;4046.271671099201;4046.7976601169826;4051.3112770828734;4056.614621610972;5686.232358631573;5806.5645076619;5814.767409303623;5845.85385365365;5870.354261031283;5338.224123455781;5340.91988620171;5347.502699244179;5350.785968561292;5370.382406023318;6310;6333.461655759266;6338.239411270677;6360.395762402564;6383.138276332682;4103.302438585448;4340.8480703175555;4461.353103799964;4487.775611219458;4489.371487991683;6569.343065693441;6624.205095388585;6638.701258443381;6662.666866656697;6663.167104111986;4427.402300689366;4445.466544014675;4456.376107344974;4458.108378324348;4461.888452788707;4270.167317373144;4469.955300447019;4471.8658440246845;4473.686841921034;4529.275315949498;5202.505761952609;5220.7128607926925;5225.869353266123;5252.632315737938;5253.711045892436;6405.711742971577;6406.711697973602;6419.101325814389;6471.5146364022885;6486.383793539606;1519.5593277949492;1565.7338252497102;1573.9606509837117;1577.5267419774023;1582.6992871354405;1003.3528374198601;1016.420640235059;1032.948352582383;1033.4677641014882;1038.159091136182;6018.337982821886;6136.2636483622255;6144.109104179869;6147.784827531104;6151.569390142737;6476.643784591798;6500.837479063061;6578.440832529289;6604.86790264192;6663.800085997428;5926.585139040312;6041.78853740117;6048;6088.939110608837;6091.177691011657;3476.252605689738;3500.6674365935705;3515.9472607910902;3525.5240542526944;3540.150363912694;6682.231543372574;6714.273578164069;6743.527953043338;6762.763303284361;6825.317468253135;6618.503612229006;6652.201735791736;6667.133272674521;6669.399754022162;6712.664366781642;6576.0872280992735;6680.565763225375;6771.830704232335;6780.593164410151;6817.7272909083085;6471.7734879279;6524.347565243438;6556.868862622816;6556.967215164084;6557.672116394257;6338.239411270677;6468.191476065492;6500.967495162494;6519.185101862267;6530.281669016499;6717.731290748511;6744.325567443217;6754.358335958076;6785.321467853175;6810.387065164159;4454.732716037048;4460.0856824350285;4471.955280447284;4475.060237350193;4490.348899409632;6419.2938776735455;6588.439982601532;6603.471722262111;6624.9337506624315;6655.600663960178;6380.712867920862;6389.2652344980925;6409.7436102555375;6422.935770642233;6442.323556026738;6227.283862355887;6382.425581697669;6423.7430502781235;6430.742770289136;6437.066625339393;6047.244094488189;6221.377862213742;6297.779577714758;6317.9052314214405;6342.79486897488;6446.387593178641;6452.6449445616945;6454.67726613658;6469.191351081115;6475.255345635313;6159.414855588712;6367.140436041261;6384.138141350858;6401.199850018747;6412.871742565216 23:18:44 INFO - PROCESS | 7265 | |5;yui.html;759.7075126076461;849.6771226933764;860.8923884514436;864.926481249094;877.8683197520376;14845.703085938274;14928.178950157737;15031.774523382132;15224.08655480672;15286.770698439504;14060.226687532208;14067.437302507913;14078.577642670736;14083.295835208224;14087.647808804784;821.0762891746784;848.932085433167;858.0218550852034;858.8797568340437;859.497194141428;819.172635638004;842.2756429470651;842.2924743215698;845.4758050009003;847.1740053447901;875;881.9382643214977;883.7437143228456;884.8274586455643;884.8584226523758;869.3088994249555;873.8558137907238;874.2656168818223;877.8024944387525;877.8244351129767;636.7230254839113;638.3552611862053;639.363832986177;640.7084776426715;642.2389468479867;15246.39014439421;15357.692846143014;15365.846341536553;15391.615209619737;15416.53750387479;14056.297185140702;14081.788773168411;14164.575062748134;14187.361568729431;14212.218327991955;11809.822852657217;11853.170278080537;11879.168458207929;11973.341466219354;11979.460924258343;3826.751261168043;3874.8643797466952;3907.159960608454;3929.76421414716;3943.9014024649614;47483.76258118722;52274.73862630701;53491.732541337435;54905;55362;10756.608023011244;11006.559737610565;11111.111111111091;11179.66461006171;11272.605458808903;19925.701114483294;19926;19926.20295188176;19933.700994485094;19938.90030549838;8380.958095209486;8557.144285571518;8619.267362274277;8863.601137948679;9041.457512549268;4843.564079232886;4844.8304309349005;4883.975580122076;4943.777530011086;4989.975050124799;3341.4152523308326;3426.5374174486124;3445.8277086145595;3446.603640581315;3456.654334566523;635.237714742311;635.4598591197445;636.6466611030922;643.2570381209711;654.1888058807045;719.4855678190014;741.4809633256742;748.135903031988;753.1000454456981;763.7632333976485;9306.581203845843;9504.287178461644;9562.856557151648;9613.327067105376;9630.759231019141;549.7910793898293;590.9261342332209;672.4855485553555;687.9724811007603;719.4963525532148;4092.529359123679;4151.398047283127;4192.161567686414;4198.538160802309;4250.596193361625;55383.446165537825;56812;60851.39148608546;61573;61582.38417615766;53899.46100538944;54267.45732542624;54355;54766.72616636891;55545;38273.61726382701;41218;46284.7685761569;49443;51644.74177629088;9375.201816221932;9798.804023919623;9809.91493978407;9872.595553334999;9941;32596.185095372806;33090;33851.15372115717;33940.49089263614;34272.48591271083;43727;47377.28934065923;47617.04765904732;47663.523364767294;47782.522174777805;11610.477528511068;11708.356040417859;12107.455164517794;12178.57374991871;12240.143189976616;13703.314834258446;14022.859771402564;14343.211123388313;14376.784348234994;14746.705065898408;12812.935935320264;12844.628312350551;13002.219866808022;13052.934735326642;13488.325583720578;12939.353032348534;13212.66968325761;13386.062975591614;13443.932780336036;13859.099158554662;13068.281244531641;13071;13573;13812.792808108088;13838.792418113535;10840.67477975662;10886.29239099456;11044.889551104387;11112.888871111185;11142.665720028412;10952.835707464235;11348;11400.771984560097;11443.370614616277;11482.94258528702 23:18:44 INFO - PROCESS | 7265 | __end_tp_report 23:18:44 INFO - PROCESS | 7265 | __start_cc_report 23:18:44 INFO - PROCESS | 7265 | _x_x_mozilla_cycle_collect,326 23:18:44 INFO - PROCESS | 7265 | __end_cc_report 23:18:44 INFO - PROCESS | 7265 | __startTimestamp1461305924010__endTimestamp 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | ------- Summary: start ------- 23:18:44 INFO - PROCESS | 7265 | Number of tests: 6 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | [#0] dojo.html Cycles:165 Average:5505.25 Median:5776.48 stddev:744.16 (12.9%) stddev-sans-first:724.08 23:18:44 INFO - PROCESS | 7265 | Values: 3197.4 3205.8 3228.0 3254.7 3272.8 5448.9 5460.3 5479.6 5491.2 5511.1 5401.4 5456.3 5551.1 5626.0 5638.7 5009.5 5032.5 5091.3 5093.3 5153.3 567.4 4998.7 5056.4 5113.1 5138.7 5107.4 5137.7 5140.4 5151.7 5162.1 5144.5 5147.4 5149.7 5157.8 5205.1 4958.5 5074.4 5129.3 5130.3 5174.4 5514.2 5529.5 5530.1 5546.7 5563.3 5754.8 6014.5 6018.4 6029.4 6041.4 5891.9 5943.3 5952.2 5960.2 5968.7 5529.9 5531.5 5534.4 5535.8 5544.7 5824.1 6031.2 6033.2 6035.9 6036.1 5914.4 5917.5 5917.7 5918.2 5970.6 5996.4 6004.3 6007.6 6008.2 6012.3 5776.5 5912.8 5921.0 5966.5 5979.6 5847.9 5878.1 5904.6 5913.5 5965.1 4462.6 4462.6 4467.2 4471.1 4507.6 3824.3 3979.5 4000.3 4010.9 4054.2 5391.9 5649.9 5652.0 5668.2 5760.9 5988.3 5988.9 5991.6 6031.0 6049.4 5379.9 5500.2 5575.7 5617.4 5678.5 5124.5 5132.5 5137.0 5145.3 5179.8 6148.3 6178.2 6212.1 6219.4 6231.7 5766.1 5812.6 6061.9 6064.8 6073.5 6158.5 6191.0 6199.3 6199.5 6201.1 5895.5 5917.6 5932.1 5932.9 5966.1 5907.5 5985.8 6004.5 6067.2 6078.3 5797.4 5813.8 5816.8 5830.8 5872.2 5785.2 5795.9 5798.9 5800.9 5804.9 5518.4 5636.3 5759.9 5821.6 5839.8 5842.7 5859.0 5888.6 5891.1 5897.0 5769.7 5773.0 5790.8 5794.0 5802.3 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | [#1] ext.html Cycles:180 Average:53721.32 Median:40191.60 stddev:101337.87 (252.1%) stddev-sans-first:101543.62 23:18:44 INFO - PROCESS | 7265 | Values: 586.8 638.3 649.4 650.3 661.0 37167.6 37745.0 38121.8 38203.4 38210.0 39819.4 40560.4 40619.8 40642.0 40729.6 8526.0 8673.3 8693.0 8693.1 8716.5 8574.8 8659.3 8662.0 8852.6 8869.4 8870.7 8936.4 8987.0 9035.3 9039.0 8868.2 8929.0 9000.2 9003.1 9021.7 8849.7 8883.6 8979.7 8987.7 9001.1 41452.2 41894.6 42078.4 42245.4 42280.4 3492.1 4102.6 4286.2 4286.8 4353.1 31767.0 31917.2 32505.7 32556.5 33472.3 4367.5 4412.2 4450.4 4480.9 4572.6 292409.0 292786.0 293728.0 294144.0 294569.1 35889.3 57600.7 58476.1 58499.4 58531.7 34936.0 35042.0 35166.5 35271.0 35271.3 15067.8 15438.3 15585.7 15604.7 15623.8 12688.8 12749.4 12752.2 12752.4 12784.1 8421.4 8688.3 8689.1 8701.1 8781.6 3298.8 3341.6 3371.2 3389.8 3397.8 1073.3 1089.4 1099.5 1194.2 1232.2 31538.5 31607.8 31709.0 31838.2 32360.4 1111.9 1172.8 1214.7 1217.5 1270.6 16378.5 16630.0 17175.7 17210.7 17259.6 449591.0 452762.0 458007.0 460111.0 461335.0 55917.7 57340.0 57346.4 57562.4 57586.7 1373.1 1386.8 1401.9 1403.4 1415.6 30050.0 30549.5 30957.5 31538.7 31713.4 50542.7 52034.2 52054.5 52059.7 52094.5 378448.0 382118.0 385120.0 385237.0 385819.0 28679.0 44822.6 45434.0 45712.8 45937.0 46244.8 46589.8 46916.0 47385.8 47571.3 39029.0 39547.0 39832.8 40230.2 40598.2 39288.2 39697.6 40153.0 40310.0 40350.8 36604.3 40298.2 41462.0 42203.0 42434.0 34381.3 35251.0 35496.8 36110.8 36158.5 27384.0 27942.2 28049.4 28336.0 28395.6 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | [#2] jquery.html Cycles:180 Average:28385.01 Median:2498.58 stddev:79756.50 (3192.1%) stddev-sans-first:79952.56 23:18:44 INFO - PROCESS | 7265 | Values: 402.9 403.2 408.3 409.4 416.6 13638.2 17496.2 18196.4 18258.5 18324.7 16934.9 17186.1 17595.3 18467.2 18534.4 5612.2 5674.1 5781.4 5816.0 5871.8 5721.2 5735.4 5767.4 5818.9 5833.6 5552.0 5722.6 5739.3 5761.4 5769.2 5721.0 5737.2 5740.1 5770.7 5778.6 4891.9 5074.4 5085.7 5089.1 5093.5 21449.5 21755.9 22058.8 22250.7 22305.9 2373.3 2442.6 2458.8 2538.4 2831.7 13632.8 14027.4 14104.0 14368.9 14519.9 1975.6 2017.3 2026.1 2038.0 2039.4 74922.6 75953.2 76850.0 77094.0 77184.2 10213.7 10278.9 10594.7 10660.1 10721.8 14853.8 14904.0 14945.1 14945.9 14972.7 8147.3 8364.6 8373.9 8415.7 8419.4 8022.1 8044.7 8062.0 8203.9 8233.3 6386.0 6449.8 6494.2 6560.7 6575.5 1976.8 1977.7 1993.7 2000.4 2031.8 978.9 1120.0 1149.2 1156.0 1196.8 16995.1 17795.9 18137.8 18217.5 18807.5 1001.1 1060.7 1075.4 1080.7 1081.2 9551.2 9828.3 9886.5 9921.6 9926.2 472795.0 472981.0 482732.0 493803.0 497035.0 92792.0 93044.5 94279.0 94398.5 95116.5 1112.8 1113.1 1118.1 1122.3 1143.9 15787.6 15832.7 15907.8 15988.9 16043.5 35770.3 36134.0 36297.3 36780.0 37907.0 52743.2 53684.0 54935.5 55731.0 58018.7 8141.6 8142.3 8233.9 8375.2 8423.6 8902.0 11874.5 12801.6 12916.5 13014.5 10903.3 11024.7 11255.8 11476.7 11501.0 11159.1 11268.5 11290.5 11299.3 11353.5 11707.5 11850.1 11895.4 11918.6 12024.7 8316.5 8351.6 8375.1 8408.6 8644.9 11571.8 11609.8 11688.5 11741.1 11848.8 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | [#3] mootools.html Cycles:180 Average:5601.70 Median:5133.72 stddev:3066.94 (59.7%) stddev-sans-first:3060.27 23:18:44 INFO - PROCESS | 7265 | Values: 1529.0 1552.1 1685.5 1778.0 1830.5 7165.8 8264.6 8401.3 8585.6 8598.7 8274.7 8300.8 8349.3 8349.7 8386.2 2368.6 2400.4 2403.7 2404.5 2404.9 2340.6 2353.8 2382.1 2385.8 2391.4 2274.5 2325.2 2332.5 2368.3 2373.2 2342.8 2345.9 2358.7 2362.8 2366.8 2266.8 2298.3 2301.5 2314.4 2320.5 8473.6 8576.1 8579.4 8580.4 8582.8 5037.2 5139.9 5202.8 5207.1 5218.6 3451.5 3519.7 3571.2 3607.5 3624.7 1049.0 1068.7 1073.4 1083.7 1089.6 11377.8 11410.2 11454.9 11483.4 11498.9 4351.2 4417.8 4523.3 4563.6 4586.7 9283.3 9339.0 9339.5 9349.3 9358.6 5127.6 5146.5 5161.9 5170.4 5178.8 4088.9 4697.1 4758.4 4809.6 4828.2 3681.3 3683.1 3719.3 3777.9 3784.5 1171.8 1175.2 1205.1 1210.7 1220.6 844.6 952.2 953.8 957.7 1000.7 5711.7 5870.5 5961.3 6078.2 6113.9 1007.8 1032.9 1084.2 1121.0 1122.6 4023.6 4056.9 4160.4 4165.1 4166.7 10221.2 10595.4 10651.2 10768.1 10774.9 10605.7 10640.1 10674.8 10685.3 10689.3 7721.5 7966.2 8030.5 8035.5 8036.9 5537.7 5602.7 5750.3 5790.7 5791.2 8718.9 8956.2 8966.5 8986.6 9271.2 9469.6 9577.5 9674.6 9713.4 9720.4 6538.2 7177.7 7276.1 7407.1 7628.8 8261.2 8286.2 8305.1 8320.6 8341.5 7025.0 7200.3 7222.7 7323.0 7386.7 7395.6 7411.5 7421.0 7455.3 7546.9 6980.5 6997.3 7026.6 7067.4 7076.3 4960.3 5061.6 5103.3 5174.7 5289.3 5320.7 5407.5 5433.3 5434.9 5520.5 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | [#4] prototype.html Cycles:180 Average:5323.03 Median:6068.54 stddev:1615.61 (26.6%) stddev-sans-first:1575.97 23:18:44 INFO - PROCESS | 7265 | Values: 324.1 327.4 329.9 338.1 340.3 6066.6 6070.5 6104.4 6123.5 6166.6 5981.6 5983.6 6009.5 6026.5 6046.1 4560.9 4571.7 4638.9 4653.8 4686.8 4613.4 4629.9 4642.0 4659.0 4662.4 4611.1 4629.6 4635.0 4639.7 4641.6 4375.7 4476.5 4624.1 4645.8 4649.6 4038.6 4046.3 4046.8 4051.3 4056.6 5686.2 5806.6 5814.8 5845.9 5870.4 5338.2 5340.9 5347.5 5350.8 5370.4 6310.0 6333.5 6338.2 6360.4 6383.1 4103.3 4340.8 4461.4 4487.8 4489.4 6569.3 6624.2 6638.7 6662.7 6663.2 4427.4 4445.5 4456.4 4458.1 4461.9 4270.2 4470.0 4471.9 4473.7 4529.3 5202.5 5220.7 5225.9 5252.6 5253.7 6405.7 6406.7 6419.1 6471.5 6486.4 1519.6 1565.7 1574.0 1577.5 1582.7 1003.4 1016.4 1032.9 1033.5 1038.2 6018.3 6136.3 6144.1 6147.8 6151.6 6476.6 6500.8 6578.4 6604.9 6663.8 5926.6 6041.8 6048.0 6088.9 6091.2 3476.3 3500.7 3515.9 3525.5 3540.2 6682.2 6714.3 6743.5 6762.8 6825.3 6618.5 6652.2 6667.1 6669.4 6712.7 6576.1 6680.6 6771.8 6780.6 6817.7 6471.8 6524.3 6556.9 6557.0 6557.7 6338.2 6468.2 6501.0 6519.2 6530.3 6717.7 6744.3 6754.4 6785.3 6810.4 4454.7 4460.1 4472.0 4475.1 4490.3 6419.3 6588.4 6603.5 6624.9 6655.6 6380.7 6389.3 6409.7 6422.9 6442.3 6227.3 6382.4 6423.7 6430.7 6437.1 6047.2 6221.4 6297.8 6317.9 6342.8 6446.4 6452.6 6454.7 6469.2 6475.3 6159.4 6367.1 6384.1 6401.2 6412.9 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - PROCESS | 7265 | [#5] yui.html Cycles:180 Average:14820.76 Median:4224.57 stddev:16512.46 (390.9%) stddev-sans-first:16525.01 23:18:44 INFO - PROCESS | 7265 | Values: 759.7 849.7 860.9 864.9 877.9 14845.7 14928.2 15031.8 15224.1 15286.8 14060.2 14067.4 14078.6 14083.3 14087.6 821.1 848.9 858.0 858.9 859.5 819.2 842.3 842.3 845.5 847.2 875.0 881.9 883.7 884.8 884.9 869.3 873.9 874.3 877.8 877.8 636.7 638.4 639.4 640.7 642.2 15246.4 15357.7 15365.8 15391.6 15416.5 14056.3 14081.8 14164.6 14187.4 14212.2 11809.8 11853.2 11879.2 11973.3 11979.5 3826.8 3874.9 3907.2 3929.8 3943.9 47483.8 52274.7 53491.7 54905.0 55362.0 10756.6 11006.6 11111.1 11179.7 11272.6 19925.7 19926.0 19926.2 19933.7 19938.9 8381.0 8557.1 8619.3 8863.6 9041.5 4843.6 4844.8 4884.0 4943.8 4990.0 3341.4 3426.5 3445.8 3446.6 3456.7 635.2 635.5 636.6 643.3 654.2 719.5 741.5 748.1 753.1 763.8 9306.6 9504.3 9562.9 9613.3 9630.8 549.8 590.9 672.5 688.0 719.5 4092.5 4151.4 4192.2 4198.5 4250.6 55383.4 56812.0 60851.4 61573.0 61582.4 53899.5 54267.5 54355.0 54766.7 55545.0 38273.6 41218.0 46284.8 49443.0 51644.7 9375.2 9798.8 9809.9 9872.6 9941.0 32596.2 33090.0 33851.2 33940.5 34272.5 43727.0 47377.3 47617.0 47663.5 47782.5 11610.5 11708.4 12107.5 12178.6 12240.1 13703.3 14022.9 14343.2 14376.8 14746.7 12812.9 12844.6 13002.2 13052.9 13488.3 12939.4 13212.7 13386.1 13443.9 13859.1 13068.3 13071.0 13573.0 13812.8 13838.8 10840.7 10886.3 11044.9 11112.9 11142.7 10952.8 11348.0 11400.8 11443.4 11482.9 23:18:44 INFO - PROCESS | 7265 | -------- Summary: end -------- 23:18:44 INFO - PROCESS | 7265 | 23:18:44 INFO - TEST-INFO | 7265: exit 0 23:18:44 INFO - TEST-OK | dromaeo_css | took 1110765ms 23:18:44 INFO - TEST-START | kraken 23:18:44 INFO - operating with platform_type : linux_ 23:18:44 INFO - Initialising browser for kraken test... 23:18:44 INFO - TEST-INFO | started process 7503 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDlD4BH/profile http://localhost:54028/getInfo.html) 23:18:48 INFO - PROCESS | 7503 | 23:18:48 INFO - PROCESS | 7503 | (process:7570): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:18:49 INFO - PROCESS | 7503 | __metrics Screen width/height:1600/1200 23:18:49 INFO - PROCESS | 7503 | colorDepth:24 23:18:49 INFO - PROCESS | 7503 | Browser inner width/height: 1024/697 23:18:49 INFO - PROCESS | 7503 | __metrics 23:18:49 ERROR - PROCESS | 7503 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 23:18:50 INFO - TEST-INFO | 7503: exit 0 23:18:50 INFO - Browser initialized. 23:18:50 INFO - Running cycle 1/1 for kraken test... 23:18:50 INFO - TEST-INFO | started process 7621 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpDlD4BH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:52 INFO - PROCESS | 7621 | 23:18:52 INFO - PROCESS | 7621 | (process:7683): GLib-CRITICAL **: g_path_get_basename: assertion `file_name != NULL' failed 23:19:02 INFO - PROCESS | 7621 | RSS: Main: 163807232 23:19:02 INFO - PROCESS | 7621 | 23:19:04 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54028/tests/kraken/driver.html?testName=audio-beat-detection) 23:19:05 INFO - PROCESS | 7621 | RSS: Main: 168460288 23:19:05 INFO - PROCESS | 7621 | 23:19:08 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54028/tests/kraken/driver.html?testName=audio-dft) 23:19:09 INFO - PROCESS | 7621 | RSS: Main: 171950080 23:19:09 INFO - PROCESS | 7621 | 23:19:12 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54028/tests/kraken/driver.html?testName=audio-fft) 23:19:12 INFO - PROCESS | 7621 | RSS: Main: 164208640 23:19:12 INFO - PROCESS | 7621 | 23:19:15 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54028/tests/kraken/driver.html?testName=audio-oscillator) 23:19:15 INFO - PROCESS | 7621 | RSS: Main: 165007360 23:19:15 INFO - PROCESS | 7621 | 23:19:17 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54028/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:19:18 INFO - PROCESS | 7621 | RSS: Main: 165150720 23:19:18 INFO - PROCESS | 7621 | 23:19:21 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54028/tests/kraken/driver.html?testName=imaging-darkroom) 23:19:22 INFO - PROCESS | 7621 | RSS: Main: 165478400 23:19:22 INFO - PROCESS | 7621 | 23:19:25 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54028/tests/kraken/driver.html?testName=imaging-desaturate) 23:19:26 INFO - PROCESS | 7621 | RSS: Main: 165965824 23:19:26 INFO - PROCESS | 7621 | 23:19:28 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54028/tests/kraken/driver.html?testName=json-parse-financial) 23:19:29 INFO - PROCESS | 7621 | RSS: Main: 165965824 23:19:29 INFO - PROCESS | 7621 | 23:19:31 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54028/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:19:31 INFO - PROCESS | 7621 | RSS: Main: 166166528 23:19:31 INFO - PROCESS | 7621 | 23:19:33 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:19:34 INFO - PROCESS | 7621 | RSS: Main: 165953536 23:19:34 INFO - PROCESS | 7621 | 23:19:36 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:19:37 INFO - PROCESS | 7621 | RSS: Main: 166178816 23:19:37 INFO - PROCESS | 7621 | 23:19:39 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:19:40 INFO - PROCESS | 7621 | RSS: Main: 166985728 23:19:40 INFO - PROCESS | 7621 | 23:19:42 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:19:43 INFO - PROCESS | 7621 | RSS: Main: 166838272 23:19:43 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | Cycle 1(1): loaded http://localhost:54028/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54028/tests/kraken/driver.html?testName=ai-astar) 23:19:45 INFO - PROCESS | 7621 | RSS: Main: 167956480 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | __start_tp_report 23:19:45 INFO - PROCESS | 7621 | _x_x_mozilla_page_load 23:19:45 INFO - PROCESS | 7621 | _x_x_mozilla_page_load_details 23:19:45 INFO - PROCESS | 7621 | |i|pagename|runs| 23:19:45 INFO - PROCESS | 7621 | |0;ai-astar;113;104;112;112;113;123;113;112;112;112 23:19:45 INFO - PROCESS | 7621 | |1;audio-beat-detection;154;129;152;180;130;154;159;127;192;131 23:19:45 INFO - PROCESS | 7621 | |2;audio-dft;161;163;162;161;162;163;174;162;164;163 23:19:45 INFO - PROCESS | 7621 | |3;audio-fft;77;76;72;158;74;81;75;74;161;77 23:19:45 INFO - PROCESS | 7621 | |4;audio-oscillator;93;93;150;92;93;93;151;93;93;147 23:19:45 INFO - PROCESS | 7621 | |5;imaging-gaussian-blur;117;118;118;119;117;117;117;117;118;118 23:19:45 INFO - PROCESS | 7621 | |6;imaging-darkroom;147;146;147;148;146;149;147;147;146;149 23:19:45 INFO - PROCESS | 7621 | |7;imaging-desaturate;96;95;97;95;96;96;96;95;95;95 23:19:45 INFO - PROCESS | 7621 | |8;json-parse-financial;60;59;61;57;61;59;58;81;56;58 23:19:45 INFO - PROCESS | 7621 | |9;json-stringify-tinderbox;68;67;67;67;67;67;65;71;70;66 23:19:45 INFO - PROCESS | 7621 | |10;stanford-crypto-aes;77;79;77;76;77;75;77;77;77;75 23:19:45 INFO - PROCESS | 7621 | |11;stanford-crypto-ccm;125;127;126;130;124;123;130;129;158;130 23:19:45 INFO - PROCESS | 7621 | |12;stanford-crypto-pbkdf2;165;163;164;167;170;165;163;169;168;159 23:19:45 INFO - PROCESS | 7621 | |13;stanford-crypto-sha256-iterative;72;68;72;70;67;68;68;72;67;71 23:19:45 INFO - PROCESS | 7621 | __end_tp_report 23:19:45 INFO - PROCESS | 7621 | __start_cc_report 23:19:45 INFO - PROCESS | 7621 | _x_x_mozilla_cycle_collect,841 23:19:45 INFO - PROCESS | 7621 | __end_cc_report 23:19:45 INFO - PROCESS | 7621 | __startTimestamp1461305985586__endTimestamp 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | ------- Summary: start ------- 23:19:45 INFO - PROCESS | 7621 | Number of tests: 14 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#0] ai-astar Cycles:10 Average:112.60 Median:112.50 stddev:4.53 (4.0%) stddev-sans-first:4.80 23:19:45 INFO - PROCESS | 7621 | Values: 113.0 104.0 112.0 112.0 113.0 123.0 113.0 112.0 112.0 112.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#1] audio-beat-detection Cycles:10 Average:150.80 Median:154.00 stddev:22.37 (14.5%) stddev-sans-first:23.70 23:19:45 INFO - PROCESS | 7621 | Values: 154.0 129.0 152.0 180.0 130.0 154.0 159.0 127.0 192.0 131.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#2] audio-dft Cycles:10 Average:163.50 Median:163.00 stddev:3.81 (2.3%) stddev-sans-first:3.93 23:19:45 INFO - PROCESS | 7621 | Values: 161.0 163.0 162.0 161.0 162.0 163.0 174.0 162.0 164.0 163.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#3] audio-fft Cycles:10 Average:92.50 Median:75.50 stddev:35.40 (46.9%) stddev-sans-first:37.10 23:19:45 INFO - PROCESS | 7621 | Values: 77.0 76.0 72.0 158.0 74.0 81.0 75.0 74.0 161.0 77.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#4] audio-oscillator Cycles:10 Average:109.80 Median:93.00 stddev:27.30 (29.4%) stddev-sans-first:28.27 23:19:45 INFO - PROCESS | 7621 | Values: 93.0 93.0 150.0 92.0 93.0 93.0 151.0 93.0 93.0 147.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#5] imaging-gaussian-blur Cycles:10 Average:117.60 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.71 23:19:45 INFO - PROCESS | 7621 | Values: 117.0 118.0 118.0 119.0 117.0 117.0 117.0 117.0 118.0 118.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#6] imaging-darkroom Cycles:10 Average:147.20 Median:147.00 stddev:1.14 (0.8%) stddev-sans-first:1.20 23:19:45 INFO - PROCESS | 7621 | Values: 147.0 146.0 147.0 148.0 146.0 149.0 147.0 147.0 146.0 149.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#7] imaging-desaturate Cycles:10 Average:95.60 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 23:19:45 INFO - PROCESS | 7621 | Values: 96.0 95.0 97.0 95.0 96.0 96.0 96.0 95.0 95.0 95.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#8] json-parse-financial Cycles:10 Average:61.00 Median:59.50 stddev:7.21 (12.1%) stddev-sans-first:7.64 23:19:45 INFO - PROCESS | 7621 | Values: 60.0 59.0 61.0 57.0 61.0 59.0 58.0 81.0 56.0 58.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:67.00 stddev:1.78 (2.7%) stddev-sans-first:1.88 23:19:45 INFO - PROCESS | 7621 | Values: 68.0 67.0 67.0 67.0 67.0 67.0 65.0 71.0 70.0 66.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#10] stanford-crypto-aes Cycles:10 Average:76.70 Median:77.00 stddev:1.16 (1.5%) stddev-sans-first:1.22 23:19:45 INFO - PROCESS | 7621 | Values: 77.0 79.0 77.0 76.0 77.0 75.0 77.0 77.0 77.0 75.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:129.50 stddev:10.11 (7.8%) stddev-sans-first:10.54 23:19:45 INFO - PROCESS | 7621 | Values: 125.0 127.0 126.0 130.0 124.0 123.0 130.0 129.0 158.0 130.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.30 Median:166.00 stddev:3.30 (2.0%) stddev-sans-first:3.50 23:19:45 INFO - PROCESS | 7621 | Values: 165.0 163.0 164.0 167.0 170.0 165.0 163.0 169.0 168.0 159.0 23:19:45 INFO - PROCESS | 7621 | 23:19:45 INFO - PROCESS | 7621 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.50 stddev:2.12 (3.0%) stddev-sans-first:2.05 23:19:45 INFO - PROCESS | 7621 | Values: 72.0 68.0 72.0 70.0 67.0 68.0 68.0 72.0 67.0 71.0 23:19:45 INFO - PROCESS | 7621 | -------- Summary: end -------- 23:19:45 INFO - PROCESS | 7621 | 23:19:46 INFO - TEST-INFO | 7621: exit 0 23:19:46 INFO - TEST-OK | kraken | took 61809ms 23:19:46 INFO - SUITE-END | took 1172s 23:19:46 INFO - Completed test suite (00:19:32) 23:19:46 INFO - javascript benchmark 23:19:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3197.4404479216137, 3205.759568032398, 3227.9515807262896, 3254.707076363127, 3272.754543409245, 5448.91826622601, 5460.262864513289, 5479.589030822694, 5491.203775452562, 5511.145772405283, 5401.405845357008, 5456.263404440399, 5551.139573366135, 5626.015447296727, 5638.718064096799, 5009.549140577347, 5032.521910418504, 5091.312672789181, 5093.337866077405, 5153.304303918961, 567.4025805996306, 4998.650094493387, 5056.367954005749, 5113.105206588851, 5138.665986710852, 5107.438181799999, 5137.6660517066275, 5140.434552199236, 5151.74241287936, 5162.1224391853475, 5144.459831717691, 5147.356580427447, 5149.665271757323, 5157.767900444489, 5205.141151710001, 4958.479359667219, 5074.441811400743, 5129.256257842592, 5130.3074084998425, 5174.3790745110455, 5514.200440936082, 5529.474699903502, 5530.059889818741, 5546.667199968015, 5563.276774019392, 5754.798582049608, 6014.458698717136, 6018.428249316308, 6029.397060293979, 6041.426064523863, 5891.852703682443, 5943.3462319144855, 5952.1964534787685, 5960.225170727777, 5968.731407086691, 5529.917051244234, 5531.529819965268, 5534.391216966131, 5535.806246781383, 5544.667319960815, 5824.097264923944, 6031.155638210654, 6033.185519954838, 6035.939640603538, 6036.124761909587, 5914.4381283777975, 5917.467427931507, 5917.733701983421, 5918.23063001807, 5970.611910225822, 5996.370381110008, 6004.279486461653, 6007.639541627429, 6008.158857759917, 6012.278526576839, 5776.480116789425, 5912.793052243151, 5920.970395147997, 5966.463018328284, 5979.641221526722, 5847.912281315784, 5878.088896221093, 5904.645721256738, 5913.46778789911, 5965.105234214816, 4462.620677242406, 4462.620677242471, 4467.24056910326, 4471.061077173765, 4507.5943165115295, 3824.2733880562605, 3979.46277252573, 4000.3399439092805, 4010.8796736097966, 4054.2094291613444, 5391.946080539223, 5649.915251271234, 5652.0391533439415, 5668.206451096849, 5760.942390576124, 5988.251468566429, 5988.880222395528, 5991.55063370249, 6031.004884194146, 6049.364816694272, 5379.892402151935, 5500.1749737538885, 5575.665460072409, 5617.354004289477, 5678.4605462480995, 5124.5131712487255, 5132.5124113209185, 5137.049645815536, 5145.305383773143, 5179.8446046618665, 6148.262208535004, 6178.227721534809, 6212.06818977148, 6219.409156130251, 6231.688415579203, 5766.106253530709, 5812.564057695605, 6061.939380606137, 6064.818055458343, 6073.544484163794, 6158.538109641884, 6191.040388739752, 6199.318075011835, 6199.5040396767245, 6201.069839524108, 5895.469407753323, 5917.644941303535, 5932.0508718604815, 5932.911006334994, 5966.105084237399, 5907.527397808251, 5985.790497332572, 6004.489618382487, 6067.180930574493, 6078.300995385587, 5797.391273916347, 5813.79651712188, 5816.82549523515, 5830.795922142875, 5872.236609240771, 5785.247917770663, 5795.8551036224435, 5798.884022319615, 5800.912986305125, 5804.912926306024, 5518.365387980433, 5636.2954630671165, 5759.9136012959, 5821.621594596338, 5839.824805255849, 5842.678652674143, 5859.0, 5888.587798854039, 5891.086881533369, 5897.0, 5769.74036168365, 5773.0, 5790.768369265183, 5793.95708772425, 5802.332731735735], "value": 5456.598401338004, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [586.7564960541375, 638.2723694987715, 649.402549654318, 650.3431534150508, 660.9702563384648, 37167.62832371679, 37745.0, 38121.80939095307, 38203.42694859579, 38210.04474888126, 39819.40270895939, 40560.391594126035, 40619.796901015456, 40642.0, 40729.59270407295, 8525.95737021314, 8673.306135509176, 8692.956535217316, 8693.087225841258, 8716.477011379306, 8574.828503429928, 8659.263962563158, 8662.0, 8852.601632926502, 8869.423487473294, 8870.73387798367, 8936.41913275635, 8987.0, 9035.27717782579, 9038.954805225965, 8868.15752503511, 8928.955355223216, 9000.189982901571, 9003.054679258681, 9021.729348119567, 8849.690260840904, 8883.644654213824, 8979.730608081769, 8987.730368088967, 9001.099890011012, 41452.170956580696, 41894.58105418937, 42078.368824467354, 42245.36631950523, 42280.365794513105, 3492.0746002309406, 4102.610252026053, 4286.164197981395, 4286.8499602514075, 4353.107612939371, 31767.0, 31917.202069948205, 32505.67494325074, 32556.511652325233, 33472.33055338904, 4367.45406824147, 4412.227860124465, 4450.421445212134, 4480.910381792346, 4572.588467037983, 292409.0, 292786.0, 293728.0, 294144.0, 294569.0543094563, 35889.28221435557, 57600.71199644059, 58476.12285815716, 58499.41500585025, 58531.70734146302, 34936.0, 35042.0, 35166.472502912475, 35271.0, 35271.294574108375, 15067.849321506865, 15438.305276262596, 15585.688286234212, 15604.68790624204, 15623.765643515357, 12688.80966785499, 12749.362531873368, 12752.171108877894, 12752.426140823685, 12784.105112642026, 8421.410501264974, 8688.304935605136, 8689.13108689126, 8701.129887011248, 8781.604827782765, 3298.802071875695, 3341.565596472443, 3371.2077661749468, 3389.796612203276, 3397.76215664906, 1073.3398959639765, 1089.4171618184234, 1099.4997276239292, 1194.2297218294152, 1232.186756740547, 31538.526922096185, 31607.84196079051, 31709.0, 31838.204044899063, 32360.35279294401, 1111.9444027798577, 1172.8006238939402, 1214.7084699672191, 1217.5251651855783, 1270.6378682075565, 16378.508644740677, 16630.0, 17175.65648687044, 17210.655786884192, 17259.568510787332, 449591.0, 452762.0, 458007.0, 460111.0, 461335.0, 55917.7204113985, 57340.0, 57346.42653573411, 57562.42437575654, 57586.7120664394, 1373.0937581196363, 1386.81278027465, 1401.88084012858, 1403.417581703602, 1415.6177831985306, 30050.0, 30549.54175687411, 30957.535636965913, 31538.684613153575, 31713.365732685677, 50542.74728626334, 52034.21948670697, 52054.479455204964, 52059.73970130125, 52094.47905520896, 378448.0, 382118.0, 385120.0, 385237.0, 385819.0, 28679.0, 44822.551774483145, 45434.0, 45712.771436143936, 45937.0, 46244.76877615725, 46589.76705116453, 46916.0, 47385.763071184425, 47571.28643070287, 39029.0, 39547.0, 39832.80083599564, 40230.19539609133, 40598.1880362397, 39288.2142357157, 39697.603023969394, 40153.0, 40310.0, 40350.84214101658, 36604.26791464209, 40298.194036118526, 41462.0, 42203.0, 42434.0, 34381.31237375289, 35251.0, 35496.82251588725, 36110.819445902605, 36158.457623135146, 27384.0, 27942.161735147976, 28049.439011219252, 28336.0, 28395.574066389432], "value": 18890.97015793693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.949631296088, 403.1533779063965, 408.26104750401794, 409.4227139732977, 416.5522063781436, 13638.181709097462, 17496.212670429828, 18196.363127290548, 18258.452246432596, 18324.725129123057, 16934.915325423386, 17186.140692965364, 17595.296188152457, 18467.168977396017, 18534.351297704583, 5612.242347283126, 5674.09214525676, 5781.39295373986, 5816.040353341694, 5871.823845284648, 5721.199032135504, 5735.397783232763, 5767.36558978512, 5818.912716309259, 5833.620814647034, 5551.9722401387935, 5722.570807189478, 5739.339975902762, 5761.423857614247, 5769.221155144044, 5720.9713951430185, 5737.196792449061, 5740.138979153118, 5770.653760774346, 5778.566607504433, 4891.926621100686, 5074.3657042869645, 5085.720285384303, 5089.083964886319, 5093.490650934914, 21449.463763405885, 21755.891220543956, 22058.779412205833, 22250.666240006412, 22305.888470557704, 2373.3473294843884, 2442.584760590704, 2458.754124587545, 2538.4034751833487, 2831.674357448899, 13632.795508067387, 14027.43890244389, 14104.0, 14368.906824356787, 14519.927400363036, 1975.595003024391, 2017.3040301096098, 2026.1186383922893, 2037.953918055611, 2039.377989713137, 74922.62538687272, 75953.24046759517, 76849.99999999945, 77094.0, 77184.22815771883, 10213.693589192337, 10278.948605256926, 10594.735131621617, 10660.147188224923, 10721.78556428867, 14853.777193342108, 14904.0, 14945.103293802407, 14945.925270373797, 14972.727318178077, 8147.348212143014, 8364.58177091143, 8373.874391884126, 8415.747527574182, 8419.410641255176, 8022.1175670676175, 8044.678212871537, 8062.0, 8203.917960820316, 8233.300169485541, 6386.0, 6449.806505804833, 6494.188226471691, 6560.671966401661, 6575.473962083021, 1976.772671142837, 1977.6539105656452, 1993.700944858254, 2000.4198782352955, 2031.7561892572985, 978.9363691360039, 1119.9664010079712, 1149.1611123879447, 1155.9768804623861, 1196.7606478704295, 16995.065271409945, 17795.932244065396, 18137.818621813614, 18217.544561385806, 18807.529811754815, 1001.1440218612981, 1060.6817954613584, 1075.381655548063, 1080.68660088575, 1081.1620993729923, 9551.188149007274, 9828.31201815866, 9886.456244906456, 9921.603135874628, 9926.2059035277, 472795.0, 472981.0, 482732.0, 493803.0, 497035.0, 92792.0, 93044.53477732703, 94279.0, 94398.5280073609, 95116.52441737747, 1112.8497652816766, 1113.0817075912244, 1118.139032944628, 1122.2649164797072, 1143.9199256052163, 15787.579117879446, 15832.683346332778, 15907.761383579487, 15988.920055399649, 16043.518694439186, 35770.28459430849, 36134.0, 36297.27405451823, 36780.0, 37907.0, 52743.20885186802, 53684.0, 54935.45064549303, 55731.0, 58018.709906450196, 8141.633626486941, 8142.267195952392, 8233.941514621345, 8375.246227839523, 8423.578821059045, 8902.020777714575, 11874.465649045986, 12801.61595152147, 12916.547920822726, 13014.544490942772, 10903.34579925207, 11024.669259921895, 11255.831162532731, 11476.655700329004, 11501.0, 11159.10727141843, 11268.549258029914, 11290.49192786343, 11299.322040677585, 11353.489092991, 11707.473163707484, 11850.111241656738, 11895.405229738306, 11918.582849600223, 12024.699382515508, 8316.501009939422, 8351.624176911931, 8375.120612335617, 8408.645579237824, 8644.91355086441, 11571.768564628828, 11609.767804644029, 11688.532458701897, 11741.060715142597, 11848.822267665819], "value": 9235.717697992506, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.9694206115878, 1552.0532475190134, 1685.4522280258918, 1777.9598934623245, 1830.4966134313054, 7165.820854478628, 8264.62809173587, 8401.327893768499, 8585.570721463926, 8598.742037738863, 8274.710385136521, 8300.83398332033, 8349.332053435726, 8349.666013359458, 8386.245237928586, 2368.5736567417816, 2400.4238982644215, 2403.67550380699, 2404.4589967257316, 2404.89177986991, 2340.578695834749, 2353.7646235376496, 2382.0709923129994, 2385.7852793248603, 2391.414103544634, 2274.4996100857784, 2325.2326732178426, 2332.4518738096535, 2368.3368656776042, 2373.22870067228, 2342.7540108288727, 2345.85924844509, 2358.705161854768, 2362.8109751219863, 2366.7869891709656, 2266.8073213776847, 2298.321995011476, 2301.516681496871, 2314.3751187179537, 2320.5474932388192, 8473.61868715903, 8576.056633770342, 8579.442336248187, 8580.39937204396, 8582.828343433159, 5037.244413337992, 5139.871503212412, 5202.817901373472, 5207.062728708831, 5218.556422704075, 3451.5340429042008, 3519.7008254298416, 3571.178628915338, 3607.5310209672575, 3624.7281453891064, 1048.958041678332, 1068.6793961811425, 1073.420353009374, 1083.7236504691332, 1089.6186334782842, 11377.772444551145, 11410.201285909907, 11454.942725286404, 11483.42582870853, 11498.942505287503, 4351.151525452569, 4417.845375411909, 4523.276275795857, 4563.589276965089, 4586.678932474695, 9283.303752218611, 9338.95330523343, 9339.533023348806, 9349.298802589832, 9358.57886395114, 5127.564157046693, 5146.459621739738, 5161.948380516147, 5170.405403378584, 5178.844634660966, 4088.897777555585, 4697.060587882437, 4758.357621721093, 4809.567138957511, 4828.155072862235, 3681.3005528949416, 3683.1160521474653, 3719.256148770257, 3777.9244415111543, 3784.508013958168, 1171.824226366052, 1175.2360965372404, 1205.0901569315286, 1210.7396909664465, 1220.6277085489012, 844.6030365728097, 952.1525842000486, 953.8092381523722, 957.7414098193449, 1000.7047920863363, 5711.657300561979, 5870.471657550841, 5961.314448838352, 6078.209832721718, 6113.908291375633, 1007.7581380468781, 1032.912202462784, 1084.2464487181492, 1120.960766373173, 1122.618309774664, 4023.5574086850424, 4056.8985775355854, 4160.375943608483, 4165.10450253197, 4166.729162604421, 10221.233407494468, 10595.417252051056, 10651.201159913038, 10768.084712799344, 10774.946125269324, 10605.734856628646, 10640.095591874622, 10674.839877401846, 10685.305455145392, 10689.30519516229, 7721.459497835098, 7966.16355282687, 8030.478018928751, 8035.517868927883, 8036.879446808302, 5537.667739935617, 5602.663840169603, 5750.338711048283, 5790.710464476843, 5791.247137872218, 8718.91281087181, 8956.193942544942, 8966.462012279284, 8986.640534378814, 9271.165595096474, 9469.621215151317, 9577.473238971923, 9674.564644591152, 9713.417194968324, 9720.368176068532, 6538.215414150332, 7177.712891484491, 7276.126864776261, 7407.148177959388, 7628.847423051397, 8261.215184557457, 8286.171382861665, 8305.086440491661, 8320.625571849161, 8341.45780524264, 7025.0, 7200.279972002758, 7222.74720384805, 7323.04800375969, 7386.741464048732, 7395.630218488946, 7411.518251313647, 7420.962895185489, 7455.254474552501, 7546.886796698163, 6980.546264492792, 6997.300269973166, 7026.578405295699, 7067.399271062018, 7076.25699301566, 4960.319431063185, 5061.569766569735, 5103.285540024325, 5174.7153906533995, 5289.259503669412, 5320.654157479751, 5407.540359069366, 5433.347998240237, 5434.945650543602, 5520.475554822286], "value": 4550.485848879659, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.0715350520759, 327.3812494385611, 329.91092405050637, 338.12257192585236, 340.3193612774451, 6066.636001839893, 6070.453659170674, 6104.389561043893, 6123.479504242139, 6166.6300021998595, 5981.58128930975, 5983.640981541111, 6009.4591486766185, 6026.548008899339, 6046.12331211974, 4560.8859778505475, 4571.674214477797, 4638.907221855561, 4653.813847446115, 4686.835960741366, 4613.400257966459, 4629.907401851961, 4641.955559999007, 4658.953410465902, 4662.3705799716945, 4611.086399144351, 4629.629629629621, 4635.0, 4639.6752227344095, 4641.605463535603, 4375.737455752649, 4476.507584165755, 4624.1445332613575, 4645.837395691135, 4649.628029757611, 4038.62686686528, 4046.271671099201, 4046.7976601169826, 4051.3112770828734, 4056.614621610972, 5686.232358631573, 5806.5645076619, 5814.767409303623, 5845.85385365365, 5870.354261031283, 5338.224123455781, 5340.91988620171, 5347.502699244179, 5350.785968561292, 5370.382406023318, 6310.0, 6333.461655759266, 6338.239411270677, 6360.395762402564, 6383.138276332682, 4103.302438585448, 4340.8480703175555, 4461.353103799964, 4487.775611219458, 4489.371487991683, 6569.343065693441, 6624.205095388585, 6638.701258443381, 6662.666866656697, 6663.167104111986, 4427.402300689366, 4445.466544014675, 4456.376107344974, 4458.108378324348, 4461.888452788707, 4270.167317373144, 4469.955300447019, 4471.8658440246845, 4473.686841921034, 4529.275315949498, 5202.505761952609, 5220.7128607926925, 5225.869353266123, 5252.632315737938, 5253.711045892436, 6405.711742971577, 6406.711697973602, 6419.101325814389, 6471.5146364022885, 6486.383793539606, 1519.5593277949492, 1565.7338252497102, 1573.9606509837117, 1577.5267419774023, 1582.6992871354405, 1003.3528374198601, 1016.420640235059, 1032.948352582383, 1033.4677641014882, 1038.159091136182, 6018.337982821886, 6136.2636483622255, 6144.109104179869, 6147.784827531104, 6151.569390142737, 6476.643784591798, 6500.837479063061, 6578.440832529289, 6604.86790264192, 6663.800085997428, 5926.585139040312, 6041.78853740117, 6048.0, 6088.939110608837, 6091.177691011657, 3476.252605689738, 3500.6674365935705, 3515.9472607910902, 3525.5240542526944, 3540.150363912694, 6682.231543372574, 6714.273578164069, 6743.527953043338, 6762.763303284361, 6825.317468253135, 6618.503612229006, 6652.201735791736, 6667.133272674521, 6669.399754022162, 6712.664366781642, 6576.0872280992735, 6680.565763225375, 6771.830704232335, 6780.593164410151, 6817.7272909083085, 6471.7734879279, 6524.347565243438, 6556.868862622816, 6556.967215164084, 6557.672116394257, 6338.239411270677, 6468.191476065492, 6500.967495162494, 6519.185101862267, 6530.281669016499, 6717.731290748511, 6744.325567443217, 6754.358335958076, 6785.321467853175, 6810.387065164159, 4454.732716037048, 4460.0856824350285, 4471.955280447284, 4475.060237350193, 4490.348899409632, 6419.2938776735455, 6588.439982601532, 6603.471722262111, 6624.9337506624315, 6655.600663960178, 6380.712867920862, 6389.2652344980925, 6409.7436102555375, 6422.935770642233, 6442.323556026738, 6227.283862355887, 6382.425581697669, 6423.7430502781235, 6430.742770289136, 6437.066625339393, 6047.244094488189, 6221.377862213742, 6297.779577714758, 6317.9052314214405, 6342.79486897488, 6446.387593178641, 6452.6449445616945, 6454.67726613658, 6469.191351081115, 6475.255345635313, 6159.414855588712, 6367.140436041261, 6384.138141350858, 6401.199850018747, 6412.871742565216], "value": 4789.641520292248, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.7075126076461, 849.6771226933764, 860.8923884514436, 864.926481249094, 877.8683197520376, 14845.703085938274, 14928.178950157737, 15031.774523382132, 15224.08655480672, 15286.770698439504, 14060.226687532208, 14067.437302507913, 14078.577642670736, 14083.295835208224, 14087.647808804784, 821.0762891746784, 848.932085433167, 858.0218550852034, 858.8797568340437, 859.497194141428, 819.172635638004, 842.2756429470651, 842.2924743215698, 845.4758050009003, 847.1740053447901, 875.0, 881.9382643214977, 883.7437143228456, 884.8274586455643, 884.8584226523758, 869.3088994249555, 873.8558137907238, 874.2656168818223, 877.8024944387525, 877.8244351129767, 636.7230254839113, 638.3552611862053, 639.363832986177, 640.7084776426715, 642.2389468479867, 15246.39014439421, 15357.692846143014, 15365.846341536553, 15391.615209619737, 15416.53750387479, 14056.297185140702, 14081.788773168411, 14164.575062748134, 14187.361568729431, 14212.218327991955, 11809.822852657217, 11853.170278080537, 11879.168458207929, 11973.341466219354, 11979.460924258343, 3826.751261168043, 3874.8643797466952, 3907.159960608454, 3929.76421414716, 3943.9014024649614, 47483.76258118722, 52274.73862630701, 53491.732541337435, 54905.0, 55362.0, 10756.608023011244, 11006.559737610565, 11111.111111111091, 11179.66461006171, 11272.605458808903, 19925.701114483294, 19926.0, 19926.20295188176, 19933.700994485094, 19938.90030549838, 8380.958095209486, 8557.144285571518, 8619.267362274277, 8863.601137948679, 9041.457512549268, 4843.564079232886, 4844.8304309349005, 4883.975580122076, 4943.777530011086, 4989.975050124799, 3341.4152523308326, 3426.5374174486124, 3445.8277086145595, 3446.603640581315, 3456.654334566523, 635.237714742311, 635.4598591197445, 636.6466611030922, 643.2570381209711, 654.1888058807045, 719.4855678190014, 741.4809633256742, 748.135903031988, 753.1000454456981, 763.7632333976485, 9306.581203845843, 9504.287178461644, 9562.856557151648, 9613.327067105376, 9630.759231019141, 549.7910793898293, 590.9261342332209, 672.4855485553555, 687.9724811007603, 719.4963525532148, 4092.529359123679, 4151.398047283127, 4192.161567686414, 4198.538160802309, 4250.596193361625, 55383.446165537825, 56812.0, 60851.39148608546, 61573.0, 61582.38417615766, 53899.46100538944, 54267.45732542624, 54355.0, 54766.72616636891, 55545.0, 38273.61726382701, 41218.0, 46284.7685761569, 49443.0, 51644.74177629088, 9375.201816221932, 9798.804023919623, 9809.91493978407, 9872.595553334999, 9941.0, 32596.185095372806, 33090.0, 33851.15372115717, 33940.49089263614, 34272.48591271083, 43727.0, 47377.28934065923, 47617.04765904732, 47663.523364767294, 47782.522174777805, 11610.477528511068, 11708.356040417859, 12107.455164517794, 12178.57374991871, 12240.143189976616, 13703.314834258446, 14022.859771402564, 14343.211123388313, 14376.784348234994, 14746.705065898408, 12812.935935320264, 12844.628312350551, 13002.219866808022, 13052.934735326642, 13488.325583720578, 12939.353032348534, 13212.66968325761, 13386.062975591614, 13443.932780336036, 13859.099158554662, 13068.281244531641, 13071.0, 13573.0, 13812.792808108088, 13838.792418113535, 10840.67477975662, 10886.29239099456, 11044.889551104387, 11112.888871111185, 11142.665720028412, 10952.835707464235, 11348.0, 11400.771984560097, 11443.370614616277, 11482.94258528702], "value": 6888.275584082808, "unit": "score"}], "name": "dromaeo_css", "value": 7230.906803482158, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 104.0, 112.0, 112.0, 113.0, 123.0, 113.0, 112.0, 112.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 129.0, 152.0, 180.0, 130.0, 154.0, 159.0, 127.0, 192.0, 131.0], "value": 150.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 161.0, 162.0, 163.0, 174.0, 162.0, 164.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 76.0, 72.0, 158.0, 74.0, 81.0, 75.0, 74.0, 161.0, 77.0], "value": 92.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 150.0, 92.0, 93.0, 93.0, 151.0, 93.0, 93.0, 147.0], "value": 109.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 118.0, 119.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0], "value": 117.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 148.0, 146.0, 149.0, 147.0, 147.0, 146.0, 149.0], "value": 147.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 95.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 61.0, 57.0, 61.0, 59.0, 58.0, 81.0, 56.0, 58.0], "value": 61.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 67.0, 67.0, 65.0, 71.0, 70.0, 66.0], "value": 67.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 77.0, 76.0, 77.0, 75.0, 77.0, 77.0, 77.0, 75.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 127.0, 126.0, 130.0, 124.0, 123.0, 130.0, 129.0, 158.0, 130.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 163.0, 164.0, 167.0, 170.0, 165.0, 163.0, 169.0, 168.0, 159.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 68.0, 72.0, 70.0, 67.0, 68.0, 68.0, 72.0, 67.0, 71.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1559.8, "alertThreshold": 2.0}]} 23:19:46 INFO - Return code: 0 23:19:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:19:46 ERROR - # TBPL SUCCESS # 23:19:46 INFO - Running post-action listener: _resource_record_post_action 23:19:46 INFO - Running post-run listener: _resource_record_post_run 23:19:47 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 339968; Write bytes: 303030272; Read time: 84; Write time: 270380 23:19:47 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 17858560; Read time: 0; Write time: 6036 23:19:48 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 339968; Write bytes: 285171712; Read time: 84; Write time: 264344 23:19:48 INFO - Running post-run listener: _upload_blobber_files 23:19:48 INFO - Blob upload gear active. 23:19:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:19:48 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 23:19:48 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:19:48 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:19:48 INFO - (blobuploader) - INFO - Open directory for files ... 23:19:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:19:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:19:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:49 INFO - (blobuploader) - INFO - Done attempting. 23:19:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:19:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:19:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:19:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:19:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:19:49 INFO - (blobuploader) - INFO - Done attempting. 23:19:49 INFO - (blobuploader) - INFO - Iteration through files over. 23:19:49 INFO - Return code: 0 23:19:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:19:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:19:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd5d9c9ff3c923240625cb6d8c7146be79ad0685c2854f0b090c2052964a9b9582bf54284b62725cd79df63b3c31677721d78f0c13354bd4f5832dd1182c0520", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4726a377829c7bc5608a2a3739eb2df44efc102886afba81e1f1ed3392da187e8ac0ba77971a235c58d8e91df6209a63a0f538d536420b00706afa49758f39d0"} 23:19:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:19:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:19:49 INFO - Contents: 23:19:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd5d9c9ff3c923240625cb6d8c7146be79ad0685c2854f0b090c2052964a9b9582bf54284b62725cd79df63b3c31677721d78f0c13354bd4f5832dd1182c0520", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4726a377829c7bc5608a2a3739eb2df44efc102886afba81e1f1ed3392da187e8ac0ba77971a235c58d8e91df6209a63a0f538d536420b00706afa49758f39d0"} 23:19:49 INFO - Running post-run listener: copy_logs_to_upload_dir 23:19:49 INFO - Copying logs to upload dir... 23:19:49 INFO - mkdir: /builds/slave/test/build/upload/logs 23:19:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1235.169943 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-04-21 23:19:49.856875) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 23:19:49.860561) ========= 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=836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848 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/Fx-Team/sha512/cd5d9c9ff3c923240625cb6d8c7146be79ad0685c2854f0b090c2052964a9b9582bf54284b62725cd79df63b3c31677721d78f0c13354bd4f5832dd1182c0520", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4726a377829c7bc5608a2a3739eb2df44efc102886afba81e1f1ed3392da187e8ac0ba77971a235c58d8e91df6209a63a0f538d536420b00706afa49758f39d0"} build_url:https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011539 build_url: 'https://queue.taskcluster.net/v1/task/bc3O2ZztRUyDh1bnHnJfqQ/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cd5d9c9ff3c923240625cb6d8c7146be79ad0685c2854f0b090c2052964a9b9582bf54284b62725cd79df63b3c31677721d78f0c13354bd4f5832dd1182c0520", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4726a377829c7bc5608a2a3739eb2df44efc102886afba81e1f1ed3392da187e8ac0ba77971a235c58d8e91df6209a63a0f538d536420b00706afa49758f39d0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 23:19:49.885673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 23:19:49.886045) ========= 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=836cfdc75598d54cb747368e000001a9-1461303296.108404-804065848 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004476 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 23:19:49.970348) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 23:19:49.970705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 23:19:49.971048) ========= ========= Total master_lag: 0.18 =========