builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-034 starttime: 1470075854.58 results: success (0) buildid: 20160801070053 builduid: eaf10adbc6074edb9e3b1e3d32dab73b revision: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.582131) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.582589) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.582867) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.779252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.779524) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 11:24:16-- 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% 222M=0s 2016-08-01 11:24:16 (222 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131183 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.938372) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.938660) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015342 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.987297) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:14.987602) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 --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=d6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 11:24:16,596 truncating revision to first 12 chars 2016-08-01 11:24:16,596 Setting DEBUG logging. 2016-08-01 11:24:16,596 attempt 1/10 2016-08-01 11:24:16,596 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/bad9d4f5bf7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 11:24:16,895 unpacking tar archive at: mozilla-beta-bad9d4f5bf7e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632512 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.637044) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.637311) ========= script_repo_revision: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.637663) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.637958) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 11:24:15.647029) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-01 11:24:15.647435) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=d6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 11:24:17 INFO - MultiFileLogger online at 20160801 11:24:17 in /builds/slave/test 11:24:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:24:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:24:17 INFO - {'append_to_log': False, 11:24:17 INFO - 'base_work_dir': '/builds/slave/test', 11:24:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:24:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:24:17 INFO - 'branch': 'Mozilla-Beta', 11:24:17 INFO - 'buildbot_json_path': 'buildprops.json', 11:24:17 INFO - 'config_files': ('talos/linux_config.py',), 11:24:17 INFO - 'default_actions': ('clobber', 11:24:17 INFO - 'read-buildbot-config', 11:24:17 INFO - 'download-and-extract', 11:24:17 INFO - 'populate-webroot', 11:24:17 INFO - 'create-virtualenv', 11:24:17 INFO - 'install', 11:24:17 INFO - 'run-tests'), 11:24:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:24:17 INFO - 'download_minidump_stackwalk': True, 11:24:17 INFO - 'download_symbols': 'ondemand', 11:24:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:24:17 INFO - 'tooltool.py': '/tools/tooltool.py', 11:24:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:24:17 INFO - '/tools/misc-python/virtualenv.py')}, 11:24:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:24:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:24:17 INFO - 'installer_path': 'installer.exe', 11:24:17 INFO - 'log_level': 'info', 11:24:17 INFO - 'log_name': 'talos', 11:24:17 INFO - 'log_to_console': True, 11:24:17 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 11:24:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 11:24:17 INFO - 'opt_config_files': (), 11:24:17 INFO - 'pip_index': False, 11:24:17 INFO - 'sps_profile': False, 11:24:17 INFO - 'sps_profile_interval': 0, 11:24:17 INFO - 'suite': 'dromaeojs', 11:24:17 INFO - 'system_bits': '32', 11:24:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:24:17 INFO - 'title': 'talos-linux64-ix-034', 11:24:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:24:17 INFO - 'use_talos_json': True, 11:24:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:24:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:24:17 INFO - 'work_dir': 'build'} 11:24:17 INFO - ##### 11:24:17 INFO - ##### Running clobber step. 11:24:17 INFO - ##### 11:24:17 INFO - Running pre-action listener: _resource_record_pre_action 11:24:17 INFO - Running main action method: clobber 11:24:17 INFO - rmtree: /builds/slave/test/build 11:24:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:24:18 INFO - Running post-action listener: _resource_record_post_action 11:24:18 INFO - ##### 11:24:18 INFO - ##### Finished clobber step (success) 11:24:18 INFO - ##### 11:24:18 INFO - ##### 11:24:18 INFO - ##### Running read-buildbot-config step. 11:24:18 INFO - ##### 11:24:18 INFO - Running pre-action listener: _resource_record_pre_action 11:24:18 INFO - Running main action method: read_buildbot_config 11:24:18 INFO - Using buildbot properties: 11:24:18 INFO - { 11:24:18 INFO - "project": "", 11:24:18 INFO - "product": "firefox", 11:24:18 INFO - "script_repo_revision": "production", 11:24:18 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 11:24:18 INFO - "repository": "", 11:24:18 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 11:24:18 INFO - "buildid": "20160801070053", 11:24:18 INFO - "pgo_build": "True", 11:24:18 INFO - "basedir": "/builds/slave/test", 11:24:18 INFO - "buildnumber": 185, 11:24:18 INFO - "slavename": "talos-linux64-ix-034", 11:24:18 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 11:24:18 INFO - "platform": "ubuntu64_hw", 11:24:18 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 11:24:18 INFO - "branch": "mozilla-beta", 11:24:18 INFO - "repo_path": "releases/mozilla-beta", 11:24:18 INFO - "revision": "bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2", 11:24:18 INFO - "stage_platform": "linux64-pgo", 11:24:18 INFO - "builduid": "eaf10adbc6074edb9e3b1e3d32dab73b", 11:24:18 INFO - "slavebuilddir": "test" 11:24:18 INFO - } 11:24:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 11:24:18 INFO - Running post-action listener: _resource_record_post_action 11:24:18 INFO - ##### 11:24:18 INFO - ##### Finished read-buildbot-config step (success) 11:24:18 INFO - ##### 11:24:18 INFO - ##### 11:24:18 INFO - ##### Running download-and-extract step. 11:24:18 INFO - ##### 11:24:18 INFO - Running pre-action listener: _resource_record_pre_action 11:24:18 INFO - Running main action method: download_and_extract 11:24:18 INFO - mkdir: /builds/slave/test/build/tests 11:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:18 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 11:24:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 11:24:20 INFO - Downloaded 1327 bytes. 11:24:20 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 11:24:20 INFO - Using the following test package requirements: 11:24:20 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 11:24:20 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 11:24:20 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 11:24:20 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'jsshell-linux-x86_64.zip'], 11:24:20 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 11:24:20 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 11:24:20 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 11:24:20 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 11:24:20 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 11:24:20 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 11:24:20 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 11:24:20 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 11:24: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')]} 11:24:20 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:24:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:24:22 INFO - Downloaded 21968684 bytes. 11:24:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:24:23 INFO - Return code: 0 11:24:23 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 11:24: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')]} 11:24:23 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 11:24:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 11:24:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 11:24:29 INFO - Downloaded 21968684 bytes. 11:24:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 11:24:30 INFO - Return code: 0 11:24:30 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')]} 11:24:30 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 11:24:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 11:24:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 11:24:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 11:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 11:24:31 INFO - Downloaded 10833369 bytes. 11:24:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:24:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 11:24:31 INFO - Return code: 0 11:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:31 INFO - https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 11:24:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 11:24:32 INFO - Downloaded 55264299 bytes. 11:24:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:32 INFO - mkdir: /builds/slave/test/properties 11:24:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:24:32 INFO - Writing to file /builds/slave/test/properties/build_url 11:24:32 INFO - Contents: 11:24:32 INFO - build_url:https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 11:24:33 INFO - Running post-action listener: _resource_record_post_action 11:24:33 INFO - Running post-action listener: set_extra_try_arguments 11:24:33 INFO - ##### 11:24:33 INFO - ##### Finished download-and-extract step (success) 11:24:33 INFO - ##### 11:24:33 INFO - ##### 11:24:33 INFO - ##### Running populate-webroot step. 11:24:33 INFO - ##### 11:24:33 INFO - Running pre-action listener: _resource_record_pre_action 11:24:33 INFO - Running main action method: populate_webroot 11:24:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:24:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:24:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:24:33 INFO - '--tppagecycles', 11:24:33 INFO - '7'], 11:24:33 INFO - 'tests': ['tsvgm']}}, 11:24:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:24:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:24:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:24:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:24:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['damp', 'tps']}, 11:24:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'tests': ['damp', 'tps']}, 11:24:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['dromaeo_dom']}, 11:24:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:24:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['a11yr', 11:24:33 INFO - 'ts_paint', 11:24:33 INFO - 'tpaint', 11:24:33 INFO - 'sessionrestore', 11:24:33 INFO - 'sessionrestore_no_auto_restore', 11:24:33 INFO - 'tabpaint']}, 11:24:33 INFO - 'other-e10s': {'tests': ['a11yr', 11:24:33 INFO - 'ts_paint', 11:24:33 INFO - 'tpaint', 11:24:33 INFO - 'sessionrestore', 11:24:33 INFO - 'sessionrestore_no_auto_restore', 11:24:33 INFO - 'tabpaint']}, 11:24:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:24:33 INFO - 'ts_paint', 11:24:33 INFO - 'tpaint', 11:24:33 INFO - 'sessionrestore', 11:24:33 INFO - 'sessionrestore_no_auto_restore', 11:24:33 INFO - 'tabpaint']}, 11:24:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:24:33 INFO - 'ts_paint', 11:24:33 INFO - 'tpaint', 11:24:33 INFO - 'sessionrestore', 11:24:33 INFO - 'sessionrestore_no_auto_restore', 11:24:33 INFO - 'tabpaint']}, 11:24:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['a11yr', 11:24:33 INFO - 'ts_paint', 11:24:33 INFO - 'tpaint', 11:24:33 INFO - 'sessionrestore', 11:24:33 INFO - 'sessionrestore_no_auto_restore', 11:24:33 INFO - 'tabpaint']}, 11:24:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['a11yr', 11:24:33 INFO - 'ts_paint', 11:24:33 INFO - 'tpaint', 11:24:33 INFO - 'sessionrestore', 11:24:33 INFO - 'sessionrestore_no_auto_restore', 11:24:33 INFO - 'tabpaint']}, 11:24:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['tsvgx', 11:24:33 INFO - 'tsvgr_opacity', 11:24:33 INFO - 'tart', 11:24:33 INFO - 'tscrollx', 11:24:33 INFO - 'cart']}, 11:24:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:24:33 INFO - 'tsvgr_opacity', 11:24:33 INFO - 'tart', 11:24:33 INFO - 'tscrollx', 11:24:33 INFO - 'cart']}, 11:24:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'talos_options': ['--disable-e10s'], 11:24:33 INFO - 'tests': ['tp5o']}, 11:24:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'tests': ['tp5o']}, 11:24:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'talos_options': ['--disable-e10s', 11:24:33 INFO - '--xperf_path', 11:24:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:33 INFO - 'tests': ['tp5n']}, 11:24:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:24:33 INFO - 'talos_options': ['--xperf_path', 11:24:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:33 INFO - 'tests': ['tp5n']}}, 11:24:33 INFO - 'talos.zip': {'path': '', 11:24:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:24:33 INFO - Running post-action listener: _resource_record_post_action 11:24:33 INFO - ##### 11:24:33 INFO - ##### Finished populate-webroot step (success) 11:24:33 INFO - ##### 11:24:33 INFO - ##### 11:24:33 INFO - ##### Running create-virtualenv step. 11:24:33 INFO - ##### 11:24:33 INFO - Running pre-action listener: _resource_record_pre_action 11:24:33 INFO - Running main action method: create_virtualenv 11:24:33 INFO - Creating virtualenv /builds/slave/test/build/venv 11:24:33 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 11:24:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:24:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:24:33 INFO - Using real prefix '/usr' 11:24:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:24:34 INFO - Installing distribute.............................................................................................................................................................................................done. 11:24:36 INFO - Installing pip.................done. 11:24:36 INFO - Return code: 0 11:24:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:24:36 INFO - Using env: {'DISPLAY': ':0', 11:24:36 INFO - 'HOME': '/home/cltbld', 11:24:36 INFO - 'LANG': 'en_US.UTF-8', 11:24:36 INFO - 'LANGUAGE': 'en_US:en', 11:24:36 INFO - 'LOGNAME': 'cltbld', 11:24:36 INFO - 'MAIL': '/var/mail/cltbld', 11:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:36 INFO - 'MOZ_NO_REMOTE': '1', 11:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:36 INFO - 'NO_EM_RESTART': '1', 11:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:36 INFO - 'PWD': '/builds/slave/test', 11:24:36 INFO - 'SHELL': '/bin/bash', 11:24:36 INFO - 'SHLVL': '1', 11:24:36 INFO - 'TERM': 'linux', 11:24:36 INFO - 'TMOUT': '86400', 11:24:36 INFO - 'USER': 'cltbld', 11:24:36 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:36 INFO - '_': '/tools/buildbot/bin/python'} 11:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:36 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 11:24:36 INFO - Cleaning up... 11:24:36 INFO - Return code: 0 11:24:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:24:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:24:36 INFO - Using env: {'DISPLAY': ':0', 11:24:36 INFO - 'HOME': '/home/cltbld', 11:24:36 INFO - 'LANG': 'en_US.UTF-8', 11:24:36 INFO - 'LANGUAGE': 'en_US:en', 11:24:36 INFO - 'LOGNAME': 'cltbld', 11:24:36 INFO - 'MAIL': '/var/mail/cltbld', 11:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:36 INFO - 'MOZ_NO_REMOTE': '1', 11:24:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:36 INFO - 'NO_EM_RESTART': '1', 11:24:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:36 INFO - 'PWD': '/builds/slave/test', 11:24:36 INFO - 'SHELL': '/bin/bash', 11:24:36 INFO - 'SHLVL': '1', 11:24:36 INFO - 'TERM': 'linux', 11:24:36 INFO - 'TMOUT': '86400', 11:24:36 INFO - 'USER': 'cltbld', 11:24:36 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:36 INFO - '_': '/tools/buildbot/bin/python'} 11:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:36 INFO - Downloading/unpacking psutil>=3.1.1 11:24:36 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 11:24:36 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 11:24:36 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 11:24:36 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 11:24:36 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 11:24:36 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 11:24:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:24:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:24:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:24:38 INFO - Installing collected packages: psutil 11:24:38 INFO - Running setup.py install for psutil 11:24:38 INFO - building 'psutil._psutil_linux' extension 11:24:38 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 11:24:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 11:24:38 INFO - building 'psutil._psutil_posix' extension 11:24:38 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 11:24:38 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 11:24:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:24:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:24:39 INFO - Successfully installed psutil 11:24:39 INFO - Cleaning up... 11:24:39 INFO - Return code: 0 11:24:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:24: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')]} 11:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24: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')]} 11:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:24:39 INFO - Using env: {'DISPLAY': ':0', 11:24:39 INFO - 'HOME': '/home/cltbld', 11:24:39 INFO - 'LANG': 'en_US.UTF-8', 11:24:39 INFO - 'LANGUAGE': 'en_US:en', 11:24:39 INFO - 'LOGNAME': 'cltbld', 11:24:39 INFO - 'MAIL': '/var/mail/cltbld', 11:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:39 INFO - 'MOZ_NO_REMOTE': '1', 11:24:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:39 INFO - 'NO_EM_RESTART': '1', 11:24:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:39 INFO - 'PWD': '/builds/slave/test', 11:24:39 INFO - 'SHELL': '/bin/bash', 11:24:39 INFO - 'SHLVL': '1', 11:24:39 INFO - 'TERM': 'linux', 11:24:39 INFO - 'TMOUT': '86400', 11:24:39 INFO - 'USER': 'cltbld', 11:24:39 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:39 INFO - '_': '/tools/buildbot/bin/python'} 11:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:24:41 INFO - Installing collected packages: mozsystemmonitor 11:24:41 INFO - Running setup.py install for mozsystemmonitor 11:24:41 INFO - Successfully installed mozsystemmonitor 11:24:41 INFO - Cleaning up... 11:24:41 INFO - Return code: 0 11:24:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:24:41 INFO - Using env: {'DISPLAY': ':0', 11:24:41 INFO - 'HOME': '/home/cltbld', 11:24:41 INFO - 'LANG': 'en_US.UTF-8', 11:24:41 INFO - 'LANGUAGE': 'en_US:en', 11:24:41 INFO - 'LOGNAME': 'cltbld', 11:24:41 INFO - 'MAIL': '/var/mail/cltbld', 11:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:41 INFO - 'MOZ_NO_REMOTE': '1', 11:24:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:41 INFO - 'NO_EM_RESTART': '1', 11:24:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:41 INFO - 'PWD': '/builds/slave/test', 11:24:41 INFO - 'SHELL': '/bin/bash', 11:24:41 INFO - 'SHLVL': '1', 11:24:41 INFO - 'TERM': 'linux', 11:24:41 INFO - 'TMOUT': '86400', 11:24:41 INFO - 'USER': 'cltbld', 11:24:41 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:41 INFO - '_': '/tools/buildbot/bin/python'} 11:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:41 INFO - Downloading/unpacking jsonschema==2.5.1 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:24:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:43 INFO - Downloading functools32-3.2.3-2.tar.gz 11:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:24:43 INFO - warning: no files found matching '*.txt' 11:24:43 INFO - no previously-included directories found matching 'build' 11:24:43 INFO - no previously-included directories found matching 'dist' 11:24:43 INFO - no previously-included directories found matching '.git*' 11:24:43 INFO - Installing collected packages: jsonschema, functools32 11:24:43 INFO - Running setup.py install for functools32 11:24:43 INFO - warning: no files found matching '*.txt' 11:24:43 INFO - no previously-included directories found matching 'build' 11:24:43 INFO - no previously-included directories found matching 'dist' 11:24:43 INFO - no previously-included directories found matching '.git*' 11:24:43 INFO - Successfully installed jsonschema functools32 11:24:43 INFO - Cleaning up... 11:24:43 INFO - Return code: 0 11:24:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:24:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:24:44 INFO - Using env: {'DISPLAY': ':0', 11:24:44 INFO - 'HOME': '/home/cltbld', 11:24:44 INFO - 'LANG': 'en_US.UTF-8', 11:24:44 INFO - 'LANGUAGE': 'en_US:en', 11:24:44 INFO - 'LOGNAME': 'cltbld', 11:24:44 INFO - 'MAIL': '/var/mail/cltbld', 11:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:44 INFO - 'MOZ_NO_REMOTE': '1', 11:24:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:44 INFO - 'NO_EM_RESTART': '1', 11:24:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:44 INFO - 'PWD': '/builds/slave/test', 11:24:44 INFO - 'SHELL': '/bin/bash', 11:24:44 INFO - 'SHLVL': '1', 11:24:44 INFO - 'TERM': 'linux', 11:24:44 INFO - 'TMOUT': '86400', 11:24:44 INFO - 'USER': 'cltbld', 11:24:44 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:44 INFO - '_': '/tools/buildbot/bin/python'} 11:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:44 INFO - Downloading/unpacking blobuploader==1.2.4 11:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:46 INFO - Downloading blobuploader-1.2.4.tar.gz 11:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:24:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:24:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:24:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:24:47 INFO - Downloading docopt-0.6.1.tar.gz 11:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:24:47 INFO - Installing collected packages: blobuploader, requests, docopt 11:24:47 INFO - Running setup.py install for blobuploader 11:24:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:24:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:24:47 INFO - Running setup.py install for requests 11:24:47 INFO - Running setup.py install for docopt 11:24:47 INFO - Successfully installed blobuploader requests docopt 11:24:47 INFO - Cleaning up... 11:24:47 INFO - Return code: 0 11:24:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:24:47 INFO - Using env: {'DISPLAY': ':0', 11:24:47 INFO - 'HOME': '/home/cltbld', 11:24:47 INFO - 'LANG': 'en_US.UTF-8', 11:24:47 INFO - 'LANGUAGE': 'en_US:en', 11:24:47 INFO - 'LOGNAME': 'cltbld', 11:24:47 INFO - 'MAIL': '/var/mail/cltbld', 11:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:47 INFO - 'MOZ_NO_REMOTE': '1', 11:24:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:47 INFO - 'NO_EM_RESTART': '1', 11:24:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:47 INFO - 'PWD': '/builds/slave/test', 11:24:47 INFO - 'SHELL': '/bin/bash', 11:24:47 INFO - 'SHLVL': '1', 11:24:47 INFO - 'TERM': 'linux', 11:24:47 INFO - 'TMOUT': '86400', 11:24:47 INFO - 'USER': 'cltbld', 11:24:47 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:47 INFO - '_': '/tools/buildbot/bin/python'} 11:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:24:47 INFO - Running setup.py (path:/tmp/pip-8exiYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:24:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:24:47 INFO - Running setup.py (path:/tmp/pip-jzbDl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:24:48 INFO - Running setup.py (path:/tmp/pip-ATc3aA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:24:48 INFO - Running setup.py (path:/tmp/pip-WypZMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:24:48 INFO - Running setup.py (path:/tmp/pip-G_YlZg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:24:48 INFO - Running setup.py (path:/tmp/pip-TQixoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:24:48 INFO - Running setup.py (path:/tmp/pip-kS3Ep7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:24:48 INFO - Running setup.py (path:/tmp/pip-jCuNxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:24:48 INFO - Running setup.py (path:/tmp/pip-PzWREG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:24:48 INFO - Running setup.py (path:/tmp/pip-qhIHRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:24:48 INFO - Running setup.py (path:/tmp/pip-LD7J0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:24:48 INFO - Running setup.py (path:/tmp/pip-9UqEgi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:24:48 INFO - Running setup.py (path:/tmp/pip-bhhcoS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:24:48 INFO - Running setup.py (path:/tmp/pip-9zXt_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:48 INFO - Running setup.py (path:/tmp/pip-MBIzNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:24:48 INFO - Running setup.py (path:/tmp/pip-QP3SVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:24:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:24:49 INFO - Running setup.py (path:/tmp/pip-R4HqQf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:24:49 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:24:49 INFO - Running setup.py install for manifestparser 11:24:49 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Running setup.py install for mozcrash 11:24:49 INFO - Running setup.py install for mozdebug 11:24:49 INFO - Running setup.py install for mozdevice 11:24:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Running setup.py install for mozfile 11:24:49 INFO - Running setup.py install for mozhttpd 11:24:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Running setup.py install for mozinfo 11:24:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Running setup.py install for mozInstall 11:24:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:24:49 INFO - Running setup.py install for mozleak 11:24:49 INFO - Running setup.py install for mozlog 11:24:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Running setup.py install for moznetwork 11:24:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Running setup.py install for mozprocess 11:24:50 INFO - Running setup.py install for mozprofile 11:24:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Running setup.py install for mozrunner 11:24:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Running setup.py install for mozscreenshot 11:24:50 INFO - Running setup.py install for moztest 11:24:50 INFO - Running setup.py install for mozversion 11:24:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:24:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:24:50 INFO - Cleaning up... 11:24:50 INFO - Return code: 0 11:24:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:24: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')]} 11:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24: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')]} 11:24:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:24:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:24:50 INFO - Using env: {'DISPLAY': ':0', 11:24:50 INFO - 'HOME': '/home/cltbld', 11:24:50 INFO - 'LANG': 'en_US.UTF-8', 11:24:50 INFO - 'LANGUAGE': 'en_US:en', 11:24:50 INFO - 'LOGNAME': 'cltbld', 11:24:50 INFO - 'MAIL': '/var/mail/cltbld', 11:24:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:50 INFO - 'MOZ_NO_REMOTE': '1', 11:24:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:50 INFO - 'NO_EM_RESTART': '1', 11:24:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:50 INFO - 'PWD': '/builds/slave/test', 11:24:50 INFO - 'SHELL': '/bin/bash', 11:24:50 INFO - 'SHLVL': '1', 11:24:50 INFO - 'TERM': 'linux', 11:24:50 INFO - 'TMOUT': '86400', 11:24:50 INFO - 'USER': 'cltbld', 11:24:50 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:50 INFO - '_': '/tools/buildbot/bin/python'} 11:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:24:51 INFO - Running setup.py (path:/tmp/pip-c6gX3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:24:51 INFO - Running setup.py (path:/tmp/pip-suQqVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:24:51 INFO - Running setup.py (path:/tmp/pip-ZaQjPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:24:51 INFO - Running setup.py (path:/tmp/pip-6Rw3Uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:24:51 INFO - Running setup.py (path:/tmp/pip-OexFyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:24:51 INFO - Running setup.py (path:/tmp/pip-AG_eqo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:24:51 INFO - Running setup.py (path:/tmp/pip-CMxLII-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:24:51 INFO - Running setup.py (path:/tmp/pip-KJf7au-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:24:51 INFO - Running setup.py (path:/tmp/pip-s3XcFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:24:51 INFO - Running setup.py (path:/tmp/pip-EifoGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:24:51 INFO - Running setup.py (path:/tmp/pip-JXMqSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:24:51 INFO - Running setup.py (path:/tmp/pip-VWDYBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:24:51 INFO - Running setup.py (path:/tmp/pip-A241NG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:24:52 INFO - Running setup.py (path:/tmp/pip-2U4_L4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:52 INFO - Running setup.py (path:/tmp/pip-UIxJS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:24:52 INFO - Running setup.py (path:/tmp/pip-0s_PCB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:24:52 INFO - Running setup.py (path:/tmp/pip-kiTYO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:24:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:24:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24:54 INFO - Downloading blessings-1.6.tar.gz 11:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:24:54 INFO - Installing collected packages: blessings 11:24:54 INFO - Running setup.py install for blessings 11:24:54 INFO - Successfully installed blessings 11:24:54 INFO - Cleaning up... 11:24:54 INFO - Return code: 0 11:24:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:24:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:24:54 INFO - Reading from file tmpfile_stdout 11:24:54 INFO - Current package versions: 11:24:54 INFO - argparse == 1.2.1 11:24:54 INFO - blessings == 1.6 11:24:54 INFO - blobuploader == 1.2.4 11:24:54 INFO - docopt == 0.6.1 11:24:54 INFO - functools32 == 3.2.3-2 11:24:54 INFO - jsonschema == 2.5.1 11:24:54 INFO - manifestparser == 1.1 11:24:54 INFO - mozInstall == 1.12 11:24:54 INFO - mozcrash == 0.17 11:24:54 INFO - mozdebug == 0.1 11:24:54 INFO - mozdevice == 0.48 11:24:54 INFO - mozfile == 1.2 11:24:54 INFO - mozhttpd == 0.7 11:24:54 INFO - mozinfo == 0.9 11:24:54 INFO - mozleak == 0.1 11:24:54 INFO - mozlog == 3.2 11:24:54 INFO - moznetwork == 0.27 11:24:54 INFO - mozprocess == 0.23 11:24:54 INFO - mozprofile == 0.28 11:24:54 INFO - mozrunner == 6.12 11:24:54 INFO - mozscreenshot == 0.1 11:24:54 INFO - mozsystemmonitor == 0.3 11:24:54 INFO - moztest == 0.7 11:24:54 INFO - mozversion == 1.4 11:24:54 INFO - psutil == 3.1.1 11:24:54 INFO - requests == 1.2.3 11:24:54 INFO - wsgiref == 0.1.2 11:24:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:24:54 INFO - Using env: {'DISPLAY': ':0', 11:24:54 INFO - 'HOME': '/home/cltbld', 11:24:54 INFO - 'LANG': 'en_US.UTF-8', 11:24:54 INFO - 'LANGUAGE': 'en_US:en', 11:24:54 INFO - 'LOGNAME': 'cltbld', 11:24:54 INFO - 'MAIL': '/var/mail/cltbld', 11:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:54 INFO - 'MOZ_NO_REMOTE': '1', 11:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:54 INFO - 'NO_EM_RESTART': '1', 11:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:54 INFO - 'PWD': '/builds/slave/test', 11:24:54 INFO - 'SHELL': '/bin/bash', 11:24:54 INFO - 'SHLVL': '1', 11:24:54 INFO - 'TERM': 'linux', 11:24:54 INFO - 'TMOUT': '86400', 11:24:54 INFO - 'USER': 'cltbld', 11:24:54 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:54 INFO - '_': '/tools/buildbot/bin/python'} 11:24:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:24:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:24:54 INFO - Cleaning up... 11:24:54 INFO - Return code: 0 11:24:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:24:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:24:54 INFO - Using env: {'DISPLAY': ':0', 11:24:54 INFO - 'HOME': '/home/cltbld', 11:24:54 INFO - 'LANG': 'en_US.UTF-8', 11:24:54 INFO - 'LANGUAGE': 'en_US:en', 11:24:54 INFO - 'LOGNAME': 'cltbld', 11:24:54 INFO - 'MAIL': '/var/mail/cltbld', 11:24:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:54 INFO - 'MOZ_NO_REMOTE': '1', 11:24:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:54 INFO - 'NO_EM_RESTART': '1', 11:24:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:54 INFO - 'PWD': '/builds/slave/test', 11:24:54 INFO - 'SHELL': '/bin/bash', 11:24:54 INFO - 'SHLVL': '1', 11:24:54 INFO - 'TERM': 'linux', 11:24:54 INFO - 'TMOUT': '86400', 11:24:54 INFO - 'USER': 'cltbld', 11:24:54 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:54 INFO - '_': '/tools/buildbot/bin/python'} 11:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:24:55 INFO - Cleaning up... 11:24:55 INFO - Return code: 0 11:24:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:24:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f848c03ff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18c48a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x198f350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, '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': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', '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 11:24:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:24:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:24:55 INFO - Using env: {'DISPLAY': ':0', 11:24:55 INFO - 'HOME': '/home/cltbld', 11:24:55 INFO - 'LANG': 'en_US.UTF-8', 11:24:55 INFO - 'LANGUAGE': 'en_US:en', 11:24:55 INFO - 'LOGNAME': 'cltbld', 11:24:55 INFO - 'MAIL': '/var/mail/cltbld', 11:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:55 INFO - 'MOZ_NO_REMOTE': '1', 11:24:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:24:55 INFO - 'NO_EM_RESTART': '1', 11:24:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:55 INFO - 'PWD': '/builds/slave/test', 11:24:55 INFO - 'SHELL': '/bin/bash', 11:24:55 INFO - 'SHLVL': '1', 11:24:55 INFO - 'TERM': 'linux', 11:24:55 INFO - 'TMOUT': '86400', 11:24:55 INFO - 'USER': 'cltbld', 11:24:55 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:55 INFO - '_': '/tools/buildbot/bin/python'} 11:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:24:55 INFO - Downloading/unpacking flake8 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24: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 11:24:57 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:24:57 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:24:57 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 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 11:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:24:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:24:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:24:57 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:24:57 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:24:57 INFO - no previously-included directories found matching 'docs/_build' 11:24:57 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:24:58 INFO - Running setup.py install for pep8 11:24:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:24:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:24:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:24:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:24:58 INFO - no previously-included directories found matching 'docs/_build' 11:24:58 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:24:58 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:24:58 INFO - Cleaning up... 11:24:58 INFO - Return code: 0 11:24:58 INFO - Running post-action listener: _resource_record_post_action 11:24:58 INFO - Running post-action listener: _start_resource_monitoring 11:24:58 INFO - Starting resource monitoring. 11:24:58 INFO - ##### 11:24:58 INFO - ##### Finished create-virtualenv step (success) 11:24:58 INFO - ##### 11:24:58 INFO - ##### 11:24:58 INFO - ##### Running install step. 11:24:58 INFO - ##### 11:24:58 INFO - Running pre-action listener: _resource_record_pre_action 11:24:58 INFO - Running main action method: install 11:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:24:58 INFO - Reading from file tmpfile_stdout 11:24:58 INFO - Detecting whether we're running mozinstall >=1.0... 11:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:24:58 INFO - Reading from file tmpfile_stdout 11:24:58 INFO - Output received: 11:24:58 INFO - Usage: mozinstall [options] installer 11:24:58 INFO - Options: 11:24:58 INFO - -h, --help show this help message and exit 11:24:58 INFO - -d DEST, --destination=DEST 11:24:58 INFO - Directory to install application into. [default: 11:24:58 INFO - "/builds/slave/test"] 11:24:58 INFO - --app=APP Application being installed. [default: firefox] 11:24:58 INFO - mkdir: /builds/slave/test/build/application 11:24:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 11:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 11:25:11 INFO - Reading from file tmpfile_stdout 11:25:11 INFO - Output received: 11:25:11 INFO - /builds/slave/test/build/application/firefox/firefox 11:25:11 INFO - Running post-action listener: _resource_record_post_action 11:25:11 INFO - ##### 11:25:11 INFO - ##### Finished install step (success) 11:25:11 INFO - ##### 11:25:11 INFO - ##### 11:25:11 INFO - ##### Running run-tests step. 11:25:11 INFO - ##### 11:25:11 INFO - Running pre-action listener: _resource_record_pre_action 11:25:11 INFO - Running main action method: run_tests 11:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:25:11 INFO - Python 2.7.3 11:25:11 INFO - Return code: 0 11:25:11 INFO - grabbing minidump binary from tooltool 11:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:25:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a1e1c0>, '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 0x1a1f0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a1f550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:25:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:25:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 11:25:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:25:11 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:25:11 INFO - Return code: 0 11:25:11 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 11:25:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:25:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:25:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 11:25:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:25:12 INFO - Return code: 0 11:25:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:25:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-034 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:25:12 INFO - Using env: {'DISPLAY': ':0', 11:25:12 INFO - 'HOME': '/home/cltbld', 11:25:12 INFO - 'LANG': 'en_US.UTF-8', 11:25:12 INFO - 'LANGUAGE': 'en_US:en', 11:25:12 INFO - 'LOGNAME': 'cltbld', 11:25:12 INFO - 'MAIL': '/var/mail/cltbld', 11:25:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:25:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 11:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:12 INFO - 'MOZ_NO_REMOTE': '1', 11:25:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 11:25:12 INFO - 'NO_EM_RESTART': '1', 11:25:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 11:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:12 INFO - 'PWD': '/builds/slave/test', 11:25:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:25:12 INFO - 'SHELL': '/bin/bash', 11:25:12 INFO - 'SHLVL': '1', 11:25:12 INFO - 'TERM': 'linux', 11:25:12 INFO - 'TMOUT': '86400', 11:25:12 INFO - 'USER': 'cltbld', 11:25:12 INFO - 'XDG_SESSION_COOKIE': 'd6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516', 11:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:12 INFO - '_': '/tools/buildbot/bin/python'} 11:25:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-034', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:25:12 INFO - mozversion application_buildid: 20160801070053 11:25:12 INFO - mozversion application_changeset: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 11:25:12 INFO - mozversion application_display_name: Firefox 11:25:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:25:12 INFO - mozversion application_name: Firefox 11:25:12 INFO - mozversion application_remotingname: firefox 11:25:12 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:25:12 INFO - mozversion application_vendor: Mozilla 11:25:12 INFO - mozversion application_version: 49.0 11:25:12 INFO - mozversion platform_buildid: 20160801070053 11:25:12 INFO - mozversion platform_changeset: bad9d4f5bf7e5ee3c0c9c443489694c90ef47af2 11:25:12 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:25:12 INFO - mozversion platform_version: 49.0 11:25:12 INFO - using testdate: 1470075912 11:25:12 INFO - actual date: 1470075912 11:25:12 INFO - starting webserver on 'localhost:34177' 11:25:12 INFO - SUITE-START | Running 2 tests 11:25:12 INFO - TEST-START | dromaeo_css 11:25:12 INFO - operating with platform_type : linux_ 11:25:12 INFO - Initialising browser for dromaeo_css test... 11:25:12 INFO - TEST-INFO | started process 7008 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpENZVnD/profile http://localhost:34177/getInfo.html) 11:25:17 INFO - PROCESS | 7008 | __metrics Screen width/height:1600/1200 11:25:17 INFO - PROCESS | 7008 | colorDepth:24 11:25:17 INFO - PROCESS | 7008 | Browser inner width/height: 1024/697 11:25:17 INFO - PROCESS | 7008 | __metrics 11:25:18 INFO - PROCESS | 7008 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:25:18 ERROR - PROCESS | 7008 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:25:18 INFO - TEST-INFO | 7008: exit 0 11:25:18 INFO - Browser initialized. 11:25:18 INFO - Running cycle 1/1 for dromaeo_css test... 11:25:18 INFO - TEST-INFO | started process 7100 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpENZVnD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:25:30 INFO - PROCESS | 7100 | RSS: Main: 143777792 11:25:30 INFO - PROCESS | 7100 | 11:26:22 INFO - PROCESS | 7100 | 1470075982037 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] 11:28:30 INFO - PROCESS | 7100 | Cycle 1(1): loaded http://localhost:34177/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34177/tests/dromaeo/cssquery-ext.html) 11:28:31 INFO - PROCESS | 7100 | RSS: Main: 255279104 11:28:31 INFO - PROCESS | 7100 | 11:31:33 INFO - PROCESS | 7100 | Cycle 1(1): loaded http://localhost:34177/tests/dromaeo/cssquery-ext.html (next: http://localhost:34177/tests/dromaeo/cssquery-jquery.html) 11:31:34 INFO - PROCESS | 7100 | RSS: Main: 229785600 11:31:34 INFO - PROCESS | 7100 | 11:34:36 INFO - PROCESS | 7100 | Cycle 1(1): loaded http://localhost:34177/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34177/tests/dromaeo/cssquery-mootools.html) 11:34:36 INFO - PROCESS | 7100 | RSS: Main: 234979328 11:34:36 INFO - PROCESS | 7100 | 11:37:38 INFO - PROCESS | 7100 | Cycle 1(1): loaded http://localhost:34177/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34177/tests/dromaeo/cssquery-prototype.html) 11:37:39 INFO - PROCESS | 7100 | RSS: Main: 220147712 11:37:39 INFO - PROCESS | 7100 | 11:40:41 INFO - PROCESS | 7100 | Cycle 1(1): loaded http://localhost:34177/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34177/tests/dromaeo/cssquery-yui.html) 11:40:42 INFO - PROCESS | 7100 | RSS: Main: 201510912 11:40:42 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | Cycle 1(1): loaded http://localhost:34177/tests/dromaeo/cssquery-yui.html (next: http://localhost:34177/tests/dromaeo/cssquery-dojo.html) 11:43:44 INFO - PROCESS | 7100 | RSS: Main: 260554752 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | __start_tp_report 11:43:44 INFO - PROCESS | 7100 | _x_x_mozilla_page_load 11:43:44 INFO - PROCESS | 7100 | _x_x_mozilla_page_load_details 11:43:44 INFO - PROCESS | 7100 | |i|pagename|runs| 11:43:44 INFO - PROCESS | 7100 | |0;dojo.html;3416.231347946714;3437.1063523483895;3452.84462199201;3472.913177170569;3479.1997840496683;5701.45836145566;5748.5975981681295;5759.1937128802;5767.538596912247;5784.710764461776;5763.452472015162;5824.504917082053;5826.9708651456785;5828.125781132832;5828.271466066742;5045.369328833895;5161.045206636784;5185.714785686795;5211.140161873286;5296.655717378378;5216.113260745663;5268.025415298182;5273.841784746444;5276.604254680914;5276.736163191844;5195.506426889439;5239.580833533309;5255.553277971377;5261.263423120766;5274.156135018397;5334.546563542103;5353.384360798519;5363.865903352408;5367.2485851980755;5368.677879327214;5151.278820965068;5154.974225128888;5178.197379406198;5240.580753539708;5255.632105752599;5664.660120392789;5689.544836413077;5716.971415142939;5724.8282551523525;5748.45389687979;6280.591761535486;6292.811215663492;6295.307516173218;6304.495640348761;6305.59013664115;6111.022236442148;6133.049377346519;6133.610107582658;6175.042868355412;6336.493080553545;5799.681017544033;5814.854628634275;5816.680082595498;5818.912716309259;5823.388544202882;6136.365905528278;6289.905651415233;6298.244210694655;6302.905456418157;6303.684815759194;6051.606645568024;6058.042977476337;6064.040241368726;6157.9384206158265;6164.599301045508;6236.126942228092;6245.937540624626;6249.375062493714;6250.156228909039;6250.15622890913;5991.041433370639;6080.087986802015;6191.628502289787;6205.131281620576;6208.255009398901;6110.80690925375;6207.037909124093;6214.5960512566535;6219.813405597839;6221.097940798559;4684.6252299816615;4709.693869898446;4723.244280915106;4724.598409135193;4750.8337208197545;4124.711270211116;4150.813213405404;4185.518665353462;4227.598378154071;4251.426057482262;6019.036954087413;6020.69896505173;6021.397860213943;6045.909311360333;6059.091136329585;6307.24313082433;6317.4946004319545;6318.4313411793155;6325.683715814283;6326.367363263636;5695.800646977336;5909.527237820964;5911.113333000085;5997.490213331916;6007;5373.7581808818695;5376.8118115865755;5386.542143917733;5390.784368625289;5406.567474602022;6427.967860160669;6478.514111441661;6483.837904052342;6488.935110648928;6494.740210391531;6025.487833534199;6141.232345956755;6203.8138855834395;6207.6585787782105;6260.342664020212;6490.870182596227;6492.285848556749;6495.0582165586675;6498.220213574212;6502.447291980235;6140.662263575547;6184.876302474016;6197;6197.876042479035;6199.380061993765;6074.969625151846;6133.242854861787;6133.570650054453;6156.630602163885;6252.062190671436;6022.849428764316;6067.575269731253;6081.4915888967535;6083.209182806207;6097.7865774697675;5890.587658863838;6031.788887388921;6047.818565443044;6048.183495228088;6052.303985041776;5928.140419639042;5981.491573216152;6012.488938440281;6025.0661147522205;6027.57806953509;6127.754889804358;6151.3541078186145;6155.969220153871;6156.168917196122;6160.876782464416;5778.248827652378;5791.449812267828;5978.342382338026;5991.430814072656;6035.094735789667 11:43:44 INFO - PROCESS | 7100 | |1;ext.html;597.8475488750368;650.9837254068649;652.6638781027768;663.2935923241747;672.0557616548745;33661.15847103821;46547.301790473124;47970.28044579325;47996.52003479964;47997.84009719562;49399.25901111486;50003;50025.24962125571;50050.74974625122;50088.74955625226;8173.63218655159;8378.12029736878;8378.49729016261;9128.589213485377;9231.446113233194;10754;11068;11078.113750899949;11394.373309467976;11397.088232941345;11190.944045279763;11272.718182045432;11279.661610151708;11289.604863829767;11302.321860688344;10998.230123891331;11050.723731906686;11088.390138542378;11094.778104437946;11102.167337449642;11016.504257308361;11091.944540277327;11092.778144437067;11098.833517497244;11099.72250693739;45376.77311613454;45528.77235613834;45571;45640.54359456396;45673;3665.7067434605433;3675.5773086095173;3796.9430458543143;3828.349180639298;3907.374820028782;34635.480467793;35105.64894351075;35921.64078359209;35945;35988.820055899814;4738.976305118486;4802.05533126345;4856.927146092811;4875.8781030474165;4880.829170978998;373792;374253;374553;377382;377558;68391.31608683949;68750.65624671844;68763.3123668767;68879;68924.6553767238;40011.79994100011;40915.18169636591;40957.385639215434;42775.572244277784;42793.35809962853;19156.521086972658;19364;19406.2237510498;19495.317663881695;19497.122629481422;13429.127106738031;13652.180869147884;13704.931475342559;14365.281735913371;14601.488947886774;21392.29369538699;21394.78605213928;21440.03519841611;21488.677669834964;21526.24658136989;7589.468737188454;7666.310032097138;7666.348360389429;7837.100272899064;7919.920800792033;1085.6905781852133;1087.211771465681;1124.2860783402598;1204.5482943896038;1223.5472875036294;34396.656033439845;34749.65250347515;34761.478577821355;34765;35227;1135.2337172408756;1158.218202713182;1209.1898428053225;1222.7004383925816;1271.6185144456626;17968.191431385618;18192.45422637323;18226.27094916215;18285.817141828677;18372.908135459238;554371;559204;560311;561701;563861;65873.67063164653;67494;67499.3250067493;67546;67683;1342.543535198018;1465.7434948883902;1485.89598728088;1498.7377208988382;1519.9392024318904;34453;35121.64878351184;35131.121721957155;35308.86642426225;35651.64348356483;50208.24687629615;50335.74832125816;50722;50734.746326268134;50778;343301;343740;343792;343797;344131;27869.72130278671;30402.239944001576;31446.213844654067;31657.525137123423;32756.344873101927;52022.47977520176;53218.201726973355;53238.46761532335;53683.19475207797;54327;44600.776996114815;44612.536735539;44650;45928.31107533323;46876.7656161717;44655.776721116184;44732.55267447284;44862.775686122666;46021.53978460307;46295.76852115853;43677.126457471306;44516.33225501555;44704.32943505915;44742.55257447514;44766;39096;40189.59810401859;40205.79897100496;40589.82004733307;40803.795981021096;30082.09753707392;30475.542866856573;30573.388532228786;30655.386892262475;30766.692333076382 11:43:44 INFO - PROCESS | 7100 | |2;jquery.html;410.0548236315292;410.3144916521367;417.68464809069155;419.65378562685794;432.41191978908677;18830.62338753224;19892.50268743282;20012.799872001295;20217;20489.18043278271;19637.803621963812;19780;19923;20165;20202.49493762653;5747.655140691561;5974.761009559613;6021.337652858182;6025.3372129065765;6033.7888173913925;4370.912581748363;4672.486026537078;5806.274215723035;5922.911156332637;6639.93360066398;6443.162388889438;6534.052562378452;6550.443212326958;6710.563813352141;6713.530052896299;6412.9679351602945;6490.772822951198;6530.771423000196;6612.702428390734;6627.37039981201;3917.490726205575;4117.732347397439;5410.3507579090365;5757.539396848242;5764.654120752726;23915.162969296165;24200.878995605086;25455;25860.87069564659;25898.352541186432;2552.3619095226195;2640.854752988585;2656.7077621461817;2680.0217920459086;2743.629610002644;15417.691646167126;16625.7506137408;16676.33294668212;16878.578035549086;17685.115744212737;2170.1970270999673;2227.20934068405;2261.954760904789;2288.2791920544973;2379.155399833069;93439.06560934371;93605;94321;95054;96129;17325.480235592953;19048.14283357252;19051.61896762057;19497.610047799248;20248.49378765513;33626.83186584051;33687.83156084204;33876.830615847255;33970;34025.65974340274;10405.791884162274;10720.624778132727;10744.838827417596;10754.085902698202;10844.186685998582;8935.553222338987;8968.820623587491;9033.63865445388;9086.45481271126;9089;9457.337986341028;9469.715908522756;9503.477308747946;9517.428954262767;9652.90347096534;3298.096407430672;3390.779599326036;3660.396034654252;3758.680512156497;3816.599257078022;938.7090002099371;982.1553464020936;1071.3893080943787;1087.5595383869388;1133.1331531378503;19246.326378576683;19513.80486195148;19684.901575492317;19765.802341976683;20023.699644505054;1022.1414012229762;1093.371311495893;1113.0307001866597;1117.1063149480383;1134.551852018448;11007.339559626447;11042.33745975244;11071.446427678584;11173.162012849069;11190.94404527972;496788;521417;523342;528140;530576;89013;93648.53175734213;94149;94195;95160;1032.850236715687;1039.2257767962917;1081.7728277061747;1110.639042311252;1116.1628778416189;17497.912510437367;17674.469765907044;17770.466885993443;17969.460916172535;18232;39028.80485597554;40018.39972400475;40827.59172408238;41588.79205603953;41727.16545669009;56599.43400566106;57359.426405737075;57771.71114144402;57852.13221801592;57901.71049144727;8611.181937715906;8734.519601421984;8866.64533418656;8872.378933474594;9317.580708867981;9520.428774273565;13009.47962081544;13300.733985320432;13557.661058473617;13589.728205436033;6493;8967.775805604912;11188.216824822184;11427.485763140512;11477.253978491372;11460.541578336773;11467.369294688871;11488.597899073493;11670.124740644316;11673.416329183678;11583.884161158281;11709.414529273332;11881.524739010343;11922.165448418527;12096.45565949552;8684.392092553713;8830.205281524693;9009;9009.549522523717;9358.578863951274;11172.162087843282;11917.582884598998;11921.403929803648;11985.101117416054;12046.036317094786 11:43:44 INFO - PROCESS | 7100 | |3;mootools.html;1465.4577806211703;1593.4184022831662;1608.0512497626403;1812.3475548802428;1942.9319973800912;9228.400153989985;9693.903060969387;9765;10016.298859079849;10028.197744180467;9765.707028789147;9979.351342162754;9981.451020193887;9996.10035096841;10005.29962902597;2473.1467643662954;2509.573372526675;2510.5230006298743;2511.4223728542447;2518.0179729905353;2406.6750988616577;2478.7149477810012;2480.417101981033;2480.987595062022;2485.751424857518;2474.2453551666736;2491.115653942851;2492.302155396492;2496.812739044579;2503.173952595639;2369;2388.6178211486254;2408.9759102408925;2446.7430919753347;2447.6083826587837;2415.7342692303832;2423.491066875958;2451.8774061296863;2453.5338285725657;2465.6671349367966;9412.858807117831;9676.854847177297;9846.310758246926;9904.504774761304;9909.702708918743;5670.3479099903625;5878.764849406019;5880.147378630118;5884.176215329857;5884.499817515516;3526.5944416392185;3639.290338384016;3641.890743277706;3710.1837595729166;3732.533433320835;1097.983530247047;1101.3502033800096;1109.4397329348708;1121;1128.7178205448638;13962.720745585133;14145.575632730932;14162.787558186532;14213.218272994982;14216.218108004055;5000.074986127578;5046.571041461517;5132.101882170605;5164.314556430635;5165.9741701292005;11909.344986025852;11959.58141465062;11977.461014254379;11978.58074967372;11984.340861252715;5849.151872978479;5853.970730146322;5855.209546711225;5857.20927674767;5858.941410585925;4655.86032419028;4757.714537127784;4777.8088876445245;5219.164933610604;5341.465853414627;4321.719088259253;4399.73601583906;4424.823007079681;4432.601065904084;4522.547745225451;1359.6532884114488;1360.5374172781103;1372.2246930484243;1381.205806661174;1401.1312985948573;1030.2221822523945;1048.7535429174138;1061.9362838229576;1068.0921216965517;1074.3607553505651;6211.3167551569295;6327.17746692927;6361.713722882481;6381.97888337873;6484.319146489645;476.20949224287517;478.2085648252079;481.9301201325788;495.5242966751886;496.26800469307483;4072.9592704073175;4089.284375234322;4538.818447262139;4701.905961880743;4824.08342414947;12427.75144497105;12751.42618582166;12763.29801860888;12804.359782010863;12811.231326120462;11804.94097529524;12232.694182645506;12282.263064216175;12715.173513721578;12768.6807829805;9105.499197544199;9204.309676774268;9261.583228754722;9265.814683706289;9325.160735533698;6616.735330586723;6857.074294970314;6915.377616014583;6970.965145174242;7071.045408869737;11499.195056345976;11514.136439767219;11636.36000020007;11792.587259445878;11821;11800.468978895799;11806.645800625995;11853.288802671867;11933.462994165457;11972.940135299268;7748.690052397841;8009.159038301127;8009.3192078673965;8446.915530844613;8740.431871928304;9257.814843703223;9321.254299656146;9399.295052870926;9406.95296523513;9536.189423899046;8483.83032339362;8501.064882863004;8548.273396761346;8554.529500877512;8751.606177722146;8192.467489613156;8270.214329638675;8465.873011904703;8499.192576705203;8517.44636598619;7641.541507509567;7669.1947345527915;7740.148583655906;7742.5741584211155;7743.032120984877;5500.312460942382;5623.015972204766;5647.181158732043;5716.742746576498;5727.541796656341;5649.4633009864;5691.089425692035;5757.193992841089;5768.971155144198;5824.650520968756 11:43:44 INFO - PROCESS | 7100 | |4;prototype.html;334.3513583647723;339.6942751523629;350.4900369961706;358.7237826873306;360.17340204231306;5705.515031222346;5712.428757124286;5735.164747280872;5750.424957504248;5753.3315338551765;5346.304980352558;5423.755930983105;5599.496045355917;5622.184783206443;5639.520640745532;4327.783610819462;4333.653307735373;4347.347897815337;4348.760818155;4365.563443655641;4364.476262848462;4381.277089280265;4394.714343567658;4398.120375924812;4410.184115938546;4177.582241775828;4195.7063005589625;4339.132173565299;4353.08585197109;4357.847475338363;4349.760763158025;4363.672724545671;4365.2142614329405;4367.912641747147;4389.473263208403;3900.687944964396;3903.7852918089493;3906.667933225679;3917.314469967744;3922.352811786052;5237.476252374732;5413.810516631938;5427.042261967237;5452.372977107604;5479.835604931858;4941.975290123526;4976.925346119811;5000.799968001275;5025.597952163818;5031.52200540948;5866.943950088982;5873.911891321591;5904.346782900795;5904.520101557313;5907.08019893532;4080.3879418087226;4145.440365550642;4155.355919832431;4183.790810459464;4187.1625674865145;6013.594562175122;6021.307549631804;6033;6043.244594425741;6059.878802423927;4330.437043184429;4340.956590434118;4346.282863327516;4352.107817897323;4352.630026447724;4300.225959327288;4324.783760811947;4354.804033818423;4379.211741886491;4381.846635367747;4933.925991110136;4939.900542269521;4948.356713627206;4950.851474455772;4993.300937868701;5961;5970.820875373745;5971.373005834324;5980.700964951822;6019.608725432833;1413.6748547834056;1435.1604311477693;1498.3631956418478;1501.9924900375647;1505.127026324729;964.7057647417465;982.8623992641036;987.5654711926729;990.2721499697716;998.7103739915489;5617.9438205618235;5735.254416925856;5762.279715035621;5791.276090488689;5806.880488621623;6095.451409373178;6104.145419641254;6107.6563156105585;6109.84725381869;6112.969435152885;5751.453611906946;5766.221560089418;5766.365699773021;5798.362180160179;5806.65160090396;3120.6567277599447;3165.8733650654176;3212.550242965987;3219.4687876500584;3257.576515053028;6086.908696369558;6111.54163437744;6148.063360041572;6173.259208894871;6197.194364732556;5913.852153696192;6041.2750469943885;6041.969790151021;6052.152698622196;6066.726997285133;6120.479759220427;6150.231221097363;6160.291566469824;6210.658413787285;6238.968805155945;5715;5919.822405327847;5982.970085149546;5990.850228744316;5993.31076926159;5822.854428639149;5834.912476312774;5862.413758624103;5871.911921321098;5920.79277225295;6115.663638499747;6135.11040899076;6140.201773769381;6150.846228844314;6172.567920245539;4130.483689538808;4311.245532031945;4331.783410829509;4351.12977404514;4352.173087113439;5954.707437636454;5985.862686089629;5992.101184822312;6010.939890601038;6027.909581356195;5548;5655.971720141373;5797.565182611405;5849.590528663123;5905.232319798571;5817.592768506164;5908.3500814911185;5919.31927828288;5922.555808314308;5925.844460330367;5782.421757824184;5821.825345239649;5826.213461182856;5845.18167456565;5918.822435326947;5704.3154821421695;5973.5519836013345;5981.1327357532045;5981.132735753378;5993.910091348721;5811.389804070512;5833;5836.241288632451;5837.970810146092;5849.53203743708 11:43:44 INFO - PROCESS | 7100 | |5;yui.html;831.5052543736479;835.1314632781907;896.4486840593036;905.3526728389201;920.6087412849539;15821.525354239377;17588.736168957475;17596.384126555542;17626.206820693067;17632.294708211655;15960.920195399007;16141.43504977326;16159;16207.027578345278;16223.26995285212;888.2316795971486;896.5114012863005;899.0649724286734;904.4030939579878;909.727081875438;884.0143240287098;888.6756333938098;889.888763904512;890.0165317324361;891.4294851295176;941.8634298026782;942.0391200975001;943.1323182671924;943.7168849345203;943.9764005899839;928.5821380378823;935.1443429262217;936.063936063936;936.66748304352;936.8454205056157;684.6200358800863;688.7451642892112;689.0112688292317;689.1351354050685;689.2659317826499;13413.262270575115;13423.530176443777;13446.663833404145;13476.797848032189;13597.932010339884;13466.394012269471;13483.123596966167;13510.662233444144;13582.93208533951;13650.726985460235;11753.88246117545;12043;12056.397180141046;12271;12314.630561083091;3998.1921218231687;4004.379321205218;4044.0496483326397;4051.113107716864;4077.938830917539;53464.19803702948;67267.66366168187;69739.65130174368;70022.29977700209;75202;12158.513659453523;13033.739325213442;13037.674058148623;13067.134313593366;13085.08404411682;21805.345839624835;24667;24712.25863224106;24723.87638061798;24763.5047299053;9447.233189817623;9471.573779179953;9475.620975161055;9547.093026162504;9912.35569687968;4449.199144154083;4512.9097418052115;4515.322701594787;4557.703749256288;4721.055788842245;3918.392649139388;3947.585503522146;4039.7374170678813;4043.939340909889;4047.1298670785923;712.2592503796097;714.7534100735238;723.9203687594361;728.9599072051088;737.7749786315118;655.2988302516262;689.3106893106893;752.9345975444794;779.9181085986003;784.2902173532962;10289.3311934724;10320.793584128276;10491;10539.05027569902;10596.258261921746;754.3178020978468;755.5693254844796;757.2124990010437;766.5247546521192;769.6257047009925;4598.954010459853;4637.513061128533;4652.302154676826;4660.976695116503;4668.486466488684;104956.47521762342;109280;111254.44372778085;115476;116646.41676791562;93783.53108234552;97863.99999999857;99241.50379248058;99462;101257;68273;81803;83738.5813070943;89822.55088724646;94967.52516237374;9771;9800.264980126376;9876.420121735448;10240.436775977392;10314.587416503557;48633.270500941806;48979.26531101965;50287.49712502828;50311.49688503068;52754.73622631862;69281.71217505675;69315;73265;74152;74819.62590187014;12190.817137742764;12319.63041108768;12358.938205308916;12699.682507937376;12807.423665934868;13198.736025279248;13533.526326578523;13557.59327220185;13626.795598065839;14155.716885662023;12451.315177664954;12813.245600490223;12838.101332906606;12849.678758031125;13250.668733281746;12738.172018818746;12757.55348562833;12776;12821.23072615646;12979.935100324437;12264.938675306566;12449.190802598165;12530.74938501243;12837.807432888327;13213.075084743976;10453.372797632166;10560.894391056298;10614;10823.350598964087;11021.944890275498;10831.2959657622;10858.67423977282;11053.11575073976;11222.831657525307;11257.943710281725 11:43:44 INFO - PROCESS | 7100 | __end_tp_report 11:43:44 INFO - PROCESS | 7100 | __start_cc_report 11:43:44 INFO - PROCESS | 7100 | _x_x_mozilla_cycle_collect,888 11:43:44 INFO - PROCESS | 7100 | __end_cc_report 11:43:44 INFO - PROCESS | 7100 | __startTimestamp1470077024978__endTimestamp 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | ------- Summary: start ------- 11:43:44 INFO - PROCESS | 7100 | Number of tests: 6 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | [#0] dojo.html Cycles:165 Average:5768.65 Median:6022.85 stddev:657.71 (10.9%) stddev-sans-first:633.31 11:43:44 INFO - PROCESS | 7100 | Values: 3416.2 3437.1 3452.8 3472.9 3479.2 5701.5 5748.6 5759.2 5767.5 5784.7 5763.5 5824.5 5827.0 5828.1 5828.3 5045.4 5161.0 5185.7 5211.1 5296.7 5216.1 5268.0 5273.8 5276.6 5276.7 5195.5 5239.6 5255.6 5261.3 5274.2 5334.5 5353.4 5363.9 5367.2 5368.7 5151.3 5155.0 5178.2 5240.6 5255.6 5664.7 5689.5 5717.0 5724.8 5748.5 6280.6 6292.8 6295.3 6304.5 6305.6 6111.0 6133.0 6133.6 6175.0 6336.5 5799.7 5814.9 5816.7 5818.9 5823.4 6136.4 6289.9 6298.2 6302.9 6303.7 6051.6 6058.0 6064.0 6157.9 6164.6 6236.1 6245.9 6249.4 6250.2 6250.2 5991.0 6080.1 6191.6 6205.1 6208.3 6110.8 6207.0 6214.6 6219.8 6221.1 4684.6 4709.7 4723.2 4724.6 4750.8 4124.7 4150.8 4185.5 4227.6 4251.4 6019.0 6020.7 6021.4 6045.9 6059.1 6307.2 6317.5 6318.4 6325.7 6326.4 5695.8 5909.5 5911.1 5997.5 6007.0 5373.8 5376.8 5386.5 5390.8 5406.6 6428.0 6478.5 6483.8 6488.9 6494.7 6025.5 6141.2 6203.8 6207.7 6260.3 6490.9 6492.3 6495.1 6498.2 6502.4 6140.7 6184.9 6197.0 6197.9 6199.4 6075.0 6133.2 6133.6 6156.6 6252.1 6022.8 6067.6 6081.5 6083.2 6097.8 5890.6 6031.8 6047.8 6048.2 6052.3 5928.1 5981.5 6012.5 6025.1 6027.6 6127.8 6151.4 6156.0 6156.2 6160.9 5778.2 5791.4 5978.3 5991.4 6035.1 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | [#1] ext.html Cycles:180 Average:60828.72 Median:375967.50 stddev:115521.44 (30.7%) stddev-sans-first:115757.00 11:43:44 INFO - PROCESS | 7100 | Values: 597.8 651.0 652.7 663.3 672.1 33661.2 46547.3 47970.3 47996.5 47997.8 49399.3 50003.0 50025.2 50050.7 50088.7 8173.6 8378.1 8378.5 9128.6 9231.4 10754.0 11068.0 11078.1 11394.4 11397.1 11190.9 11272.7 11279.7 11289.6 11302.3 10998.2 11050.7 11088.4 11094.8 11102.2 11016.5 11091.9 11092.8 11098.8 11099.7 45376.8 45528.8 45571.0 45640.5 45673.0 3665.7 3675.6 3796.9 3828.3 3907.4 34635.5 35105.6 35921.6 35945.0 35988.8 4739.0 4802.1 4856.9 4875.9 4880.8 373792.0 374253.0 374553.0 377382.0 377558.0 68391.3 68750.7 68763.3 68879.0 68924.7 40011.8 40915.2 40957.4 42775.6 42793.4 19156.5 19364.0 19406.2 19495.3 19497.1 13429.1 13652.2 13704.9 14365.3 14601.5 21392.3 21394.8 21440.0 21488.7 21526.2 7589.5 7666.3 7666.3 7837.1 7919.9 1085.7 1087.2 1124.3 1204.5 1223.5 34396.7 34749.7 34761.5 34765.0 35227.0 1135.2 1158.2 1209.2 1222.7 1271.6 17968.2 18192.5 18226.3 18285.8 18372.9 554371.0 559204.0 560311.0 561701.0 563861.0 65873.7 67494.0 67499.3 67546.0 67683.0 1342.5 1465.7 1485.9 1498.7 1519.9 34453.0 35121.6 35131.1 35308.9 35651.6 50208.2 50335.7 50722.0 50734.7 50778.0 343301.0 343740.0 343792.0 343797.0 344131.0 27869.7 30402.2 31446.2 31657.5 32756.3 52022.5 53218.2 53238.5 53683.2 54327.0 44600.8 44612.5 44650.0 45928.3 46876.8 44655.8 44732.6 44862.8 46021.5 46295.8 43677.1 44516.3 44704.3 44742.6 44766.0 39096.0 40189.6 40205.8 40589.8 40803.8 30082.1 30475.5 30573.4 30655.4 30766.7 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | [#2] jquery.html Cycles:180 Average:31539.68 Median:18462.46 stddev:85669.14 (464.0%) stddev-sans-first:85877.58 11:43:44 INFO - PROCESS | 7100 | Values: 410.1 410.3 417.7 419.7 432.4 18830.6 19892.5 20012.8 20217.0 20489.2 19637.8 19780.0 19923.0 20165.0 20202.5 5747.7 5974.8 6021.3 6025.3 6033.8 4370.9 4672.5 5806.3 5922.9 6639.9 6443.2 6534.1 6550.4 6710.6 6713.5 6413.0 6490.8 6530.8 6612.7 6627.4 3917.5 4117.7 5410.4 5757.5 5764.7 23915.2 24200.9 25455.0 25860.9 25898.4 2552.4 2640.9 2656.7 2680.0 2743.6 15417.7 16625.8 16676.3 16878.6 17685.1 2170.2 2227.2 2262.0 2288.3 2379.2 93439.1 93605.0 94321.0 95054.0 96129.0 17325.5 19048.1 19051.6 19497.6 20248.5 33626.8 33687.8 33876.8 33970.0 34025.7 10405.8 10720.6 10744.8 10754.1 10844.2 8935.6 8968.8 9033.6 9086.5 9089.0 9457.3 9469.7 9503.5 9517.4 9652.9 3298.1 3390.8 3660.4 3758.7 3816.6 938.7 982.2 1071.4 1087.6 1133.1 19246.3 19513.8 19684.9 19765.8 20023.7 1022.1 1093.4 1113.0 1117.1 1134.6 11007.3 11042.3 11071.4 11173.2 11190.9 496788.0 521417.0 523342.0 528140.0 530576.0 89013.0 93648.5 94149.0 94195.0 95160.0 1032.9 1039.2 1081.8 1110.6 1116.2 17497.9 17674.5 17770.5 17969.5 18232.0 39028.8 40018.4 40827.6 41588.8 41727.2 56599.4 57359.4 57771.7 57852.1 57901.7 8611.2 8734.5 8866.6 8872.4 9317.6 9520.4 13009.5 13300.7 13557.7 13589.7 6493.0 8967.8 11188.2 11427.5 11477.3 11460.5 11467.4 11488.6 11670.1 11673.4 11583.9 11709.4 11881.5 11922.2 12096.5 8684.4 8830.2 9009.0 9009.5 9358.6 11172.2 11917.6 11921.4 11985.1 12046.0 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | [#3] mootools.html Cycles:180 Average:6459.10 Median:4597.34 stddev:3837.82 (83.5%) stddev-sans-first:3830.24 11:43:44 INFO - PROCESS | 7100 | Values: 1465.5 1593.4 1608.1 1812.3 1942.9 9228.4 9693.9 9765.0 10016.3 10028.2 9765.7 9979.4 9981.5 9996.1 10005.3 2473.1 2509.6 2510.5 2511.4 2518.0 2406.7 2478.7 2480.4 2481.0 2485.8 2474.2 2491.1 2492.3 2496.8 2503.2 2369.0 2388.6 2409.0 2446.7 2447.6 2415.7 2423.5 2451.9 2453.5 2465.7 9412.9 9676.9 9846.3 9904.5 9909.7 5670.3 5878.8 5880.1 5884.2 5884.5 3526.6 3639.3 3641.9 3710.2 3732.5 1098.0 1101.4 1109.4 1121.0 1128.7 13962.7 14145.6 14162.8 14213.2 14216.2 5000.1 5046.6 5132.1 5164.3 5166.0 11909.3 11959.6 11977.5 11978.6 11984.3 5849.2 5854.0 5855.2 5857.2 5858.9 4655.9 4757.7 4777.8 5219.2 5341.5 4321.7 4399.7 4424.8 4432.6 4522.5 1359.7 1360.5 1372.2 1381.2 1401.1 1030.2 1048.8 1061.9 1068.1 1074.4 6211.3 6327.2 6361.7 6382.0 6484.3 476.2 478.2 481.9 495.5 496.3 4073.0 4089.3 4538.8 4701.9 4824.1 12427.8 12751.4 12763.3 12804.4 12811.2 11804.9 12232.7 12282.3 12715.2 12768.7 9105.5 9204.3 9261.6 9265.8 9325.2 6616.7 6857.1 6915.4 6971.0 7071.0 11499.2 11514.1 11636.4 11792.6 11821.0 11800.5 11806.6 11853.3 11933.5 11972.9 7748.7 8009.2 8009.3 8446.9 8740.4 9257.8 9321.3 9399.3 9407.0 9536.2 8483.8 8501.1 8548.3 8554.5 8751.6 8192.5 8270.2 8465.9 8499.2 8517.4 7641.5 7669.2 7740.1 7742.6 7743.0 5500.3 5623.0 5647.2 5716.7 5727.5 5649.5 5691.1 5757.2 5769.0 5824.7 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | [#4] prototype.html Cycles:180 Average:4946.88 Median:5752.39 stddev:1455.86 (25.3%) stddev-sans-first:1418.19 11:43:44 INFO - PROCESS | 7100 | Values: 334.4 339.7 350.5 358.7 360.2 5705.5 5712.4 5735.2 5750.4 5753.3 5346.3 5423.8 5599.5 5622.2 5639.5 4327.8 4333.7 4347.3 4348.8 4365.6 4364.5 4381.3 4394.7 4398.1 4410.2 4177.6 4195.7 4339.1 4353.1 4357.8 4349.8 4363.7 4365.2 4367.9 4389.5 3900.7 3903.8 3906.7 3917.3 3922.4 5237.5 5413.8 5427.0 5452.4 5479.8 4942.0 4976.9 5000.8 5025.6 5031.5 5866.9 5873.9 5904.3 5904.5 5907.1 4080.4 4145.4 4155.4 4183.8 4187.2 6013.6 6021.3 6033.0 6043.2 6059.9 4330.4 4341.0 4346.3 4352.1 4352.6 4300.2 4324.8 4354.8 4379.2 4381.8 4933.9 4939.9 4948.4 4950.9 4993.3 5961.0 5970.8 5971.4 5980.7 6019.6 1413.7 1435.2 1498.4 1502.0 1505.1 964.7 982.9 987.6 990.3 998.7 5617.9 5735.3 5762.3 5791.3 5806.9 6095.5 6104.1 6107.7 6109.8 6113.0 5751.5 5766.2 5766.4 5798.4 5806.7 3120.7 3165.9 3212.6 3219.5 3257.6 6086.9 6111.5 6148.1 6173.3 6197.2 5913.9 6041.3 6042.0 6052.2 6066.7 6120.5 6150.2 6160.3 6210.7 6239.0 5715.0 5919.8 5983.0 5990.9 5993.3 5822.9 5834.9 5862.4 5871.9 5920.8 6115.7 6135.1 6140.2 6150.8 6172.6 4130.5 4311.2 4331.8 4351.1 4352.2 5954.7 5985.9 5992.1 6010.9 6027.9 5548.0 5656.0 5797.6 5849.6 5905.2 5817.6 5908.4 5919.3 5922.6 5925.8 5782.4 5821.8 5826.2 5845.2 5918.8 5704.3 5973.6 5981.1 5981.1 5993.9 5811.4 5833.0 5836.2 5838.0 5849.5 11:43:44 INFO - PROCESS | 7100 | 11:43:44 INFO - PROCESS | 7100 | [#5] yui.html Cycles:180 Average:20372.35 Median:4049.12 stddev:28871.91 (713.0%) stddev-sans-first:28915.62 11:43:44 INFO - PROCESS | 7100 | Values: 831.5 835.1 896.4 905.4 920.6 15821.5 17588.7 17596.4 17626.2 17632.3 15960.9 16141.4 16159.0 16207.0 16223.3 888.2 896.5 899.1 904.4 909.7 884.0 888.7 889.9 890.0 891.4 941.9 942.0 943.1 943.7 944.0 928.6 935.1 936.1 936.7 936.8 684.6 688.7 689.0 689.1 689.3 13413.3 13423.5 13446.7 13476.8 13597.9 13466.4 13483.1 13510.7 13582.9 13650.7 11753.9 12043.0 12056.4 12271.0 12314.6 3998.2 4004.4 4044.0 4051.1 4077.9 53464.2 67267.7 69739.7 70022.3 75202.0 12158.5 13033.7 13037.7 13067.1 13085.1 21805.3 24667.0 24712.3 24723.9 24763.5 9447.2 9471.6 9475.6 9547.1 9912.4 4449.2 4512.9 4515.3 4557.7 4721.1 3918.4 3947.6 4039.7 4043.9 4047.1 712.3 714.8 723.9 729.0 737.8 655.3 689.3 752.9 779.9 784.3 10289.3 10320.8 10491.0 10539.1 10596.3 754.3 755.6 757.2 766.5 769.6 4599.0 4637.5 4652.3 4661.0 4668.5 104956.5 109280.0 111254.4 115476.0 116646.4 93783.5 97864.0 99241.5 99462.0 101257.0 68273.0 81803.0 83738.6 89822.6 94967.5 9771.0 9800.3 9876.4 10240.4 10314.6 48633.3 48979.3 50287.5 50311.5 52754.7 69281.7 69315.0 73265.0 74152.0 74819.6 12190.8 12319.6 12358.9 12699.7 12807.4 13198.7 13533.5 13557.6 13626.8 14155.7 12451.3 12813.2 12838.1 12849.7 13250.7 12738.2 12757.6 12776.0 12821.2 12979.9 12264.9 12449.2 12530.7 12837.8 13213.1 10453.4 10560.9 10614.0 10823.4 11021.9 10831.3 10858.7 11053.1 11222.8 11257.9 11:43:44 INFO - PROCESS | 7100 | -------- Summary: end -------- 11:43:44 INFO - PROCESS | 7100 | 11:43:45 INFO - TEST-INFO | 7100: exit 0 11:43:45 INFO - TEST-OK | dromaeo_css | took 1112858ms 11:43:45 INFO - TEST-START | kraken 11:43:45 INFO - operating with platform_type : linux_ 11:43:45 INFO - Initialising browser for kraken test... 11:43:45 INFO - TEST-INFO | started process 7289 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppwCpxn/profile http://localhost:34177/getInfo.html) 11:43:49 INFO - PROCESS | 7289 | __metrics Screen width/height:1600/1200 11:43:49 INFO - PROCESS | 7289 | colorDepth:24 11:43:49 INFO - PROCESS | 7289 | Browser inner width/height: 1024/697 11:43:49 INFO - PROCESS | 7289 | __metrics 11:43:50 INFO - PROCESS | 7289 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:43:50 ERROR - PROCESS | 7289 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:43:51 INFO - TEST-INFO | 7289: exit 0 11:43:51 INFO - Browser initialized. 11:43:51 INFO - Running cycle 1/1 for kraken test... 11:43:51 INFO - TEST-INFO | started process 7378 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppwCpxn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:44:03 INFO - PROCESS | 7378 | RSS: Main: 141058048 11:44:03 INFO - PROCESS | 7378 | 11:44:06 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34177/tests/kraken/driver.html?testName=audio-beat-detection) 11:44:06 INFO - PROCESS | 7378 | RSS: Main: 191991808 11:44:06 INFO - PROCESS | 7378 | 11:44:09 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34177/tests/kraken/driver.html?testName=audio-dft) 11:44:10 INFO - PROCESS | 7378 | RSS: Main: 189784064 11:44:10 INFO - PROCESS | 7378 | 11:44:12 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34177/tests/kraken/driver.html?testName=audio-fft) 11:44:13 INFO - PROCESS | 7378 | RSS: Main: 276934656 11:44:13 INFO - PROCESS | 7378 | 11:44:15 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34177/tests/kraken/driver.html?testName=audio-oscillator) 11:44:16 INFO - PROCESS | 7378 | RSS: Main: 185655296 11:44:16 INFO - PROCESS | 7378 | 11:44:18 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34177/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:44:18 INFO - PROCESS | 7378 | RSS: Main: 186208256 11:44:18 INFO - PROCESS | 7378 | 11:44:21 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34177/tests/kraken/driver.html?testName=imaging-darkroom) 11:44:22 INFO - PROCESS | 7378 | RSS: Main: 220606464 11:44:22 INFO - PROCESS | 7378 | 11:44:25 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34177/tests/kraken/driver.html?testName=imaging-desaturate) 11:44:26 INFO - PROCESS | 7378 | RSS: Main: 218636288 11:44:26 INFO - PROCESS | 7378 | 11:44:29 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34177/tests/kraken/driver.html?testName=json-parse-financial) 11:44:29 INFO - PROCESS | 7378 | RSS: Main: 219308032 11:44:29 INFO - PROCESS | 7378 | 11:44:31 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34177/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:44:31 INFO - PROCESS | 7378 | RSS: Main: 198889472 11:44:31 INFO - PROCESS | 7378 | 11:44:33 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:44:34 INFO - PROCESS | 7378 | RSS: Main: 199380992 11:44:34 INFO - PROCESS | 7378 | 11:44:36 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:44:36 INFO - PROCESS | 7378 | RSS: Main: 207613952 11:44:36 INFO - PROCESS | 7378 | 11:44:39 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:44:39 INFO - PROCESS | 7378 | RSS: Main: 211103744 11:44:39 INFO - PROCESS | 7378 | 11:44:42 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:44:43 INFO - PROCESS | 7378 | RSS: Main: 212537344 11:44:43 INFO - PROCESS | 7378 | 11:44:44 INFO - PROCESS | 7378 | Cycle 1(1): loaded http://localhost:34177/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34177/tests/kraken/driver.html?testName=ai-astar) 11:44:45 INFO - PROCESS | 7378 | RSS: Main: 254849024 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | __start_tp_report 11:44:45 INFO - PROCESS | 7378 | _x_x_mozilla_page_load 11:44:45 INFO - PROCESS | 7378 | _x_x_mozilla_page_load_details 11:44:45 INFO - PROCESS | 7378 | |i|pagename|runs| 11:44:45 INFO - PROCESS | 7378 | |0;ai-astar;110;105;109;112;109;112;108;110;112;108 11:44:45 INFO - PROCESS | 7378 | |1;audio-beat-detection;157;123;124;172;123;123;146;124;153;123 11:44:45 INFO - PROCESS | 7378 | |2;audio-dft;160;163;160;159;161;160;161;161;159;160 11:44:45 INFO - PROCESS | 7378 | |3;audio-fft;75;75;74;112;74;75;75;75;97;75 11:44:45 INFO - PROCESS | 7378 | |4;audio-oscillator;84;85;116;84;84;83;119;83;85;129 11:44:45 INFO - PROCESS | 7378 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;117 11:44:45 INFO - PROCESS | 7378 | |6;imaging-darkroom;145;146;146;148;146;145;146;146;145;146 11:44:45 INFO - PROCESS | 7378 | |7;imaging-desaturate;94;94;94;95;94;94;95;94;95;94 11:44:45 INFO - PROCESS | 7378 | |8;json-parse-financial;50;57;52;51;51;52;52;50;51;52 11:44:45 INFO - PROCESS | 7378 | |9;json-stringify-tinderbox;59;60;59;60;60;60;60;60;60;59 11:44:45 INFO - PROCESS | 7378 | |10;stanford-crypto-aes;76;77;81;86;84;78;78;81;116;76 11:44:45 INFO - PROCESS | 7378 | |11;stanford-crypto-ccm;127;117;121;118;115;117;115;117;122;119 11:44:45 INFO - PROCESS | 7378 | |12;stanford-crypto-pbkdf2;156;161;159;154;156;152;157;161;157;154 11:44:45 INFO - PROCESS | 7378 | |13;stanford-crypto-sha256-iterative;64;60;61;60;61;63;61;61;60;61 11:44:45 INFO - PROCESS | 7378 | __end_tp_report 11:44:45 INFO - PROCESS | 7378 | __start_cc_report 11:44:45 INFO - PROCESS | 7378 | _x_x_mozilla_cycle_collect,1551 11:44:45 INFO - PROCESS | 7378 | __end_cc_report 11:44:45 INFO - PROCESS | 7378 | __startTimestamp1470077085049__endTimestamp 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | ------- Summary: start ------- 11:44:45 INFO - PROCESS | 7378 | Number of tests: 14 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#0] ai-astar Cycles:10 Average:109.50 Median:110.00 stddev:2.22 (2.0%) stddev-sans-first:2.35 11:44:45 INFO - PROCESS | 7378 | Values: 110.0 105.0 109.0 112.0 109.0 112.0 108.0 110.0 112.0 108.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#1] audio-beat-detection Cycles:10 Average:136.80 Median:135.00 stddev:18.51 (13.7%) stddev-sans-first:18.13 11:44:45 INFO - PROCESS | 7378 | Values: 157.0 123.0 124.0 172.0 123.0 123.0 146.0 124.0 153.0 123.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:1.17 (0.7%) stddev-sans-first:1.24 11:44:45 INFO - PROCESS | 7378 | Values: 160.0 163.0 160.0 159.0 161.0 160.0 161.0 161.0 159.0 160.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#3] audio-fft Cycles:10 Average:80.70 Median:75.00 stddev:13.04 (17.4%) stddev-sans-first:13.67 11:44:45 INFO - PROCESS | 7378 | Values: 75.0 75.0 74.0 112.0 74.0 75.0 75.0 75.0 97.0 75.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:84.00 stddev:18.33 (21.8%) stddev-sans-first:18.99 11:44:45 INFO - PROCESS | 7378 | Values: 84.0 85.0 116.0 84.0 84.0 83.0 119.0 83.0 85.0 129.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 11:44:45 INFO - PROCESS | 7378 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#6] imaging-darkroom Cycles:10 Average:145.90 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.87 11:44:45 INFO - PROCESS | 7378 | Values: 145.0 146.0 146.0 148.0 146.0 145.0 146.0 146.0 145.0 146.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 11:44:45 INFO - PROCESS | 7378 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:52.00 stddev:1.99 (3.8%) stddev-sans-first:2.00 11:44:45 INFO - PROCESS | 7378 | Values: 50.0 57.0 52.0 51.0 51.0 52.0 52.0 50.0 51.0 52.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:60.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 11:44:45 INFO - PROCESS | 7378 | Values: 59.0 60.0 59.0 60.0 60.0 60.0 60.0 60.0 60.0 59.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:79.50 stddev:11.97 (15.1%) stddev-sans-first:12.40 11:44:45 INFO - PROCESS | 7378 | Values: 76.0 77.0 81.0 86.0 84.0 78.0 78.0 81.0 116.0 76.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#11] stanford-crypto-ccm Cycles:10 Average:118.80 Median:118.50 stddev:3.68 (3.1%) stddev-sans-first:2.42 11:44:45 INFO - PROCESS | 7378 | Values: 127.0 117.0 121.0 118.0 115.0 117.0 115.0 117.0 122.0 119.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.70 Median:157.00 stddev:2.98 (1.9%) stddev-sans-first:3.15 11:44:45 INFO - PROCESS | 7378 | Values: 156.0 161.0 159.0 154.0 156.0 152.0 157.0 161.0 157.0 154.0 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - PROCESS | 7378 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.00 stddev:1.32 (2.2%) stddev-sans-first:0.93 11:44:45 INFO - PROCESS | 7378 | Values: 64.0 60.0 61.0 60.0 61.0 63.0 61.0 61.0 60.0 61.0 11:44:45 INFO - PROCESS | 7378 | -------- Summary: end -------- 11:44:45 INFO - PROCESS | 7378 | 11:44:45 INFO - TEST-INFO | 7378: exit 0 11:44:46 INFO - TEST-OK | kraken | took 60313ms 11:44:46 INFO - SUITE-END | took 1173s 11:44:46 INFO - Completed test suite (00:19:33) 11:44:46 INFO - javascript benchmark 11:44:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3416.231347946714, 3437.1063523483895, 3452.84462199201, 3472.913177170569, 3479.1997840496683, 5701.45836145566, 5748.5975981681295, 5759.1937128802, 5767.538596912247, 5784.710764461776, 5763.452472015162, 5824.504917082053, 5826.9708651456785, 5828.125781132832, 5828.271466066742, 5045.369328833895, 5161.045206636784, 5185.714785686795, 5211.140161873286, 5296.655717378378, 5216.113260745663, 5268.025415298182, 5273.841784746444, 5276.604254680914, 5276.736163191844, 5195.506426889439, 5239.580833533309, 5255.553277971377, 5261.263423120766, 5274.156135018397, 5334.546563542103, 5353.384360798519, 5363.865903352408, 5367.2485851980755, 5368.677879327214, 5151.278820965068, 5154.974225128888, 5178.197379406198, 5240.580753539708, 5255.632105752599, 5664.660120392789, 5689.544836413077, 5716.971415142939, 5724.8282551523525, 5748.45389687979, 6280.591761535486, 6292.811215663492, 6295.307516173218, 6304.495640348761, 6305.59013664115, 6111.022236442148, 6133.049377346519, 6133.610107582658, 6175.042868355412, 6336.493080553545, 5799.681017544033, 5814.854628634275, 5816.680082595498, 5818.912716309259, 5823.388544202882, 6136.365905528278, 6289.905651415233, 6298.244210694655, 6302.905456418157, 6303.684815759194, 6051.606645568024, 6058.042977476337, 6064.040241368726, 6157.9384206158265, 6164.599301045508, 6236.126942228092, 6245.937540624626, 6249.375062493714, 6250.156228909039, 6250.15622890913, 5991.041433370639, 6080.087986802015, 6191.628502289787, 6205.131281620576, 6208.255009398901, 6110.80690925375, 6207.037909124093, 6214.5960512566535, 6219.813405597839, 6221.097940798559, 4684.6252299816615, 4709.693869898446, 4723.244280915106, 4724.598409135193, 4750.8337208197545, 4124.711270211116, 4150.813213405404, 4185.518665353462, 4227.598378154071, 4251.426057482262, 6019.036954087413, 6020.69896505173, 6021.397860213943, 6045.909311360333, 6059.091136329585, 6307.24313082433, 6317.4946004319545, 6318.4313411793155, 6325.683715814283, 6326.367363263636, 5695.800646977336, 5909.527237820964, 5911.113333000085, 5997.490213331916, 6007.0, 5373.7581808818695, 5376.8118115865755, 5386.542143917733, 5390.784368625289, 5406.567474602022, 6427.967860160669, 6478.514111441661, 6483.837904052342, 6488.935110648928, 6494.740210391531, 6025.487833534199, 6141.232345956755, 6203.8138855834395, 6207.6585787782105, 6260.342664020212, 6490.870182596227, 6492.285848556749, 6495.0582165586675, 6498.220213574212, 6502.447291980235, 6140.662263575547, 6184.876302474016, 6197.0, 6197.876042479035, 6199.380061993765, 6074.969625151846, 6133.242854861787, 6133.570650054453, 6156.630602163885, 6252.062190671436, 6022.849428764316, 6067.575269731253, 6081.4915888967535, 6083.209182806207, 6097.7865774697675, 5890.587658863838, 6031.788887388921, 6047.818565443044, 6048.183495228088, 6052.303985041776, 5928.140419639042, 5981.491573216152, 6012.488938440281, 6025.0661147522205, 6027.57806953509, 6127.754889804358, 6151.3541078186145, 6155.969220153871, 6156.168917196122, 6160.876782464416, 5778.248827652378, 5791.449812267828, 5978.342382338026, 5991.430814072656, 6035.094735789667], "value": 5724.648949328402, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.8475488750368, 650.9837254068649, 652.6638781027768, 663.2935923241747, 672.0557616548745, 33661.15847103821, 46547.301790473124, 47970.28044579325, 47996.52003479964, 47997.84009719562, 49399.25901111486, 50003.0, 50025.24962125571, 50050.74974625122, 50088.74955625226, 8173.63218655159, 8378.12029736878, 8378.49729016261, 9128.589213485377, 9231.446113233194, 10754.0, 11068.0, 11078.113750899949, 11394.373309467976, 11397.088232941345, 11190.944045279763, 11272.718182045432, 11279.661610151708, 11289.604863829767, 11302.321860688344, 10998.230123891331, 11050.723731906686, 11088.390138542378, 11094.778104437946, 11102.167337449642, 11016.504257308361, 11091.944540277327, 11092.778144437067, 11098.833517497244, 11099.72250693739, 45376.77311613454, 45528.77235613834, 45571.0, 45640.54359456396, 45673.0, 3665.7067434605433, 3675.5773086095173, 3796.9430458543143, 3828.349180639298, 3907.374820028782, 34635.480467793, 35105.64894351075, 35921.64078359209, 35945.0, 35988.820055899814, 4738.976305118486, 4802.05533126345, 4856.927146092811, 4875.8781030474165, 4880.829170978998, 373792.0, 374253.0, 374553.0, 377382.0, 377558.0, 68391.31608683949, 68750.65624671844, 68763.3123668767, 68879.0, 68924.6553767238, 40011.79994100011, 40915.18169636591, 40957.385639215434, 42775.572244277784, 42793.35809962853, 19156.521086972658, 19364.0, 19406.2237510498, 19495.317663881695, 19497.122629481422, 13429.127106738031, 13652.180869147884, 13704.931475342559, 14365.281735913371, 14601.488947886774, 21392.29369538699, 21394.78605213928, 21440.03519841611, 21488.677669834964, 21526.24658136989, 7589.468737188454, 7666.310032097138, 7666.348360389429, 7837.100272899064, 7919.920800792033, 1085.6905781852133, 1087.211771465681, 1124.2860783402598, 1204.5482943896038, 1223.5472875036294, 34396.656033439845, 34749.65250347515, 34761.478577821355, 34765.0, 35227.0, 1135.2337172408756, 1158.218202713182, 1209.1898428053225, 1222.7004383925816, 1271.6185144456626, 17968.191431385618, 18192.45422637323, 18226.27094916215, 18285.817141828677, 18372.908135459238, 554371.0, 559204.0, 560311.0, 561701.0, 563861.0, 65873.67063164653, 67494.0, 67499.3250067493, 67546.0, 67683.0, 1342.543535198018, 1465.7434948883902, 1485.89598728088, 1498.7377208988382, 1519.9392024318904, 34453.0, 35121.64878351184, 35131.121721957155, 35308.86642426225, 35651.64348356483, 50208.24687629615, 50335.74832125816, 50722.0, 50734.746326268134, 50778.0, 343301.0, 343740.0, 343792.0, 343797.0, 344131.0, 27869.72130278671, 30402.239944001576, 31446.213844654067, 31657.525137123423, 32756.344873101927, 52022.47977520176, 53218.201726973355, 53238.46761532335, 53683.19475207797, 54327.0, 44600.776996114815, 44612.536735539, 44650.0, 45928.31107533323, 46876.7656161717, 44655.776721116184, 44732.55267447284, 44862.775686122666, 46021.53978460307, 46295.76852115853, 43677.126457471306, 44516.33225501555, 44704.32943505915, 44742.55257447514, 44766.0, 39096.0, 40189.59810401859, 40205.79897100496, 40589.82004733307, 40803.795981021096, 30082.09753707392, 30475.542866856573, 30573.388532228786, 30655.386892262475, 30766.692333076382], "value": 21779.531277234597, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.0548236315292, 410.3144916521367, 417.68464809069155, 419.65378562685794, 432.41191978908677, 18830.62338753224, 19892.50268743282, 20012.799872001295, 20217.0, 20489.18043278271, 19637.803621963812, 19780.0, 19923.0, 20165.0, 20202.49493762653, 5747.655140691561, 5974.761009559613, 6021.337652858182, 6025.3372129065765, 6033.7888173913925, 4370.912581748363, 4672.486026537078, 5806.274215723035, 5922.911156332637, 6639.93360066398, 6443.162388889438, 6534.052562378452, 6550.443212326958, 6710.563813352141, 6713.530052896299, 6412.9679351602945, 6490.772822951198, 6530.771423000196, 6612.702428390734, 6627.37039981201, 3917.490726205575, 4117.732347397439, 5410.3507579090365, 5757.539396848242, 5764.654120752726, 23915.162969296165, 24200.878995605086, 25455.0, 25860.87069564659, 25898.352541186432, 2552.3619095226195, 2640.854752988585, 2656.7077621461817, 2680.0217920459086, 2743.629610002644, 15417.691646167126, 16625.7506137408, 16676.33294668212, 16878.578035549086, 17685.115744212737, 2170.1970270999673, 2227.20934068405, 2261.954760904789, 2288.2791920544973, 2379.155399833069, 93439.06560934371, 93605.0, 94321.0, 95054.0, 96129.0, 17325.480235592953, 19048.14283357252, 19051.61896762057, 19497.610047799248, 20248.49378765513, 33626.83186584051, 33687.83156084204, 33876.830615847255, 33970.0, 34025.65974340274, 10405.791884162274, 10720.624778132727, 10744.838827417596, 10754.085902698202, 10844.186685998582, 8935.553222338987, 8968.820623587491, 9033.63865445388, 9086.45481271126, 9089.0, 9457.337986341028, 9469.715908522756, 9503.477308747946, 9517.428954262767, 9652.90347096534, 3298.096407430672, 3390.779599326036, 3660.396034654252, 3758.680512156497, 3816.599257078022, 938.7090002099371, 982.1553464020936, 1071.3893080943787, 1087.5595383869388, 1133.1331531378503, 19246.326378576683, 19513.80486195148, 19684.901575492317, 19765.802341976683, 20023.699644505054, 1022.1414012229762, 1093.371311495893, 1113.0307001866597, 1117.1063149480383, 1134.551852018448, 11007.339559626447, 11042.33745975244, 11071.446427678584, 11173.162012849069, 11190.94404527972, 496788.0, 521417.0, 523342.0, 528140.0, 530576.0, 89013.0, 93648.53175734213, 94149.0, 94195.0, 95160.0, 1032.850236715687, 1039.2257767962917, 1081.7728277061747, 1110.639042311252, 1116.1628778416189, 17497.912510437367, 17674.469765907044, 17770.466885993443, 17969.460916172535, 18232.0, 39028.80485597554, 40018.39972400475, 40827.59172408238, 41588.79205603953, 41727.16545669009, 56599.43400566106, 57359.426405737075, 57771.71114144402, 57852.13221801592, 57901.71049144727, 8611.181937715906, 8734.519601421984, 8866.64533418656, 8872.378933474594, 9317.580708867981, 9520.428774273565, 13009.47962081544, 13300.733985320432, 13557.661058473617, 13589.728205436033, 6493.0, 8967.775805604912, 11188.216824822184, 11427.485763140512, 11477.253978491372, 11460.541578336773, 11467.369294688871, 11488.597899073493, 11670.124740644316, 11673.416329183678, 11583.884161158281, 11709.414529273332, 11881.524739010343, 11922.165448418527, 12096.45565949552, 8684.392092553713, 8830.205281524693, 9009.0, 9009.549522523717, 9358.578863951274, 11172.162087843282, 11917.582884598998, 11921.403929803648, 11985.101117416054, 12046.036317094786], "value": 10464.740743009386, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1465.4577806211703, 1593.4184022831662, 1608.0512497626403, 1812.3475548802428, 1942.9319973800912, 9228.400153989985, 9693.903060969387, 9765.0, 10016.298859079849, 10028.197744180467, 9765.707028789147, 9979.351342162754, 9981.451020193887, 9996.10035096841, 10005.29962902597, 2473.1467643662954, 2509.573372526675, 2510.5230006298743, 2511.4223728542447, 2518.0179729905353, 2406.6750988616577, 2478.7149477810012, 2480.417101981033, 2480.987595062022, 2485.751424857518, 2474.2453551666736, 2491.115653942851, 2492.302155396492, 2496.812739044579, 2503.173952595639, 2369.0, 2388.6178211486254, 2408.9759102408925, 2446.7430919753347, 2447.6083826587837, 2415.7342692303832, 2423.491066875958, 2451.8774061296863, 2453.5338285725657, 2465.6671349367966, 9412.858807117831, 9676.854847177297, 9846.310758246926, 9904.504774761304, 9909.702708918743, 5670.3479099903625, 5878.764849406019, 5880.147378630118, 5884.176215329857, 5884.499817515516, 3526.5944416392185, 3639.290338384016, 3641.890743277706, 3710.1837595729166, 3732.533433320835, 1097.983530247047, 1101.3502033800096, 1109.4397329348708, 1121.0, 1128.7178205448638, 13962.720745585133, 14145.575632730932, 14162.787558186532, 14213.218272994982, 14216.218108004055, 5000.074986127578, 5046.571041461517, 5132.101882170605, 5164.314556430635, 5165.9741701292005, 11909.344986025852, 11959.58141465062, 11977.461014254379, 11978.58074967372, 11984.340861252715, 5849.151872978479, 5853.970730146322, 5855.209546711225, 5857.20927674767, 5858.941410585925, 4655.86032419028, 4757.714537127784, 4777.8088876445245, 5219.164933610604, 5341.465853414627, 4321.719088259253, 4399.73601583906, 4424.823007079681, 4432.601065904084, 4522.547745225451, 1359.6532884114488, 1360.5374172781103, 1372.2246930484243, 1381.205806661174, 1401.1312985948573, 1030.2221822523945, 1048.7535429174138, 1061.9362838229576, 1068.0921216965517, 1074.3607553505651, 6211.3167551569295, 6327.17746692927, 6361.713722882481, 6381.97888337873, 6484.319146489645, 476.20949224287517, 478.2085648252079, 481.9301201325788, 495.5242966751886, 496.26800469307483, 4072.9592704073175, 4089.284375234322, 4538.818447262139, 4701.905961880743, 4824.08342414947, 12427.75144497105, 12751.42618582166, 12763.29801860888, 12804.359782010863, 12811.231326120462, 11804.94097529524, 12232.694182645506, 12282.263064216175, 12715.173513721578, 12768.6807829805, 9105.499197544199, 9204.309676774268, 9261.583228754722, 9265.814683706289, 9325.160735533698, 6616.735330586723, 6857.074294970314, 6915.377616014583, 6970.965145174242, 7071.045408869737, 11499.195056345976, 11514.136439767219, 11636.36000020007, 11792.587259445878, 11821.0, 11800.468978895799, 11806.645800625995, 11853.288802671867, 11933.462994165457, 11972.940135299268, 7748.690052397841, 8009.159038301127, 8009.3192078673965, 8446.915530844613, 8740.431871928304, 9257.814843703223, 9321.254299656146, 9399.295052870926, 9406.95296523513, 9536.189423899046, 8483.83032339362, 8501.064882863004, 8548.273396761346, 8554.529500877512, 8751.606177722146, 8192.467489613156, 8270.214329638675, 8465.873011904703, 8499.192576705203, 8517.44636598619, 7641.541507509567, 7669.1947345527915, 7740.148583655906, 7742.5741584211155, 7743.032120984877, 5500.312460942382, 5623.015972204766, 5647.181158732043, 5716.742746576498, 5727.541796656341, 5649.4633009864, 5691.089425692035, 5757.193992841089, 5768.971155144198, 5824.650520968756], "value": 4998.658480220549, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.3513583647723, 339.6942751523629, 350.4900369961706, 358.7237826873306, 360.17340204231306, 5705.515031222346, 5712.428757124286, 5735.164747280872, 5750.424957504248, 5753.3315338551765, 5346.304980352558, 5423.755930983105, 5599.496045355917, 5622.184783206443, 5639.520640745532, 4327.783610819462, 4333.653307735373, 4347.347897815337, 4348.760818155, 4365.563443655641, 4364.476262848462, 4381.277089280265, 4394.714343567658, 4398.120375924812, 4410.184115938546, 4177.582241775828, 4195.7063005589625, 4339.132173565299, 4353.08585197109, 4357.847475338363, 4349.760763158025, 4363.672724545671, 4365.2142614329405, 4367.912641747147, 4389.473263208403, 3900.687944964396, 3903.7852918089493, 3906.667933225679, 3917.314469967744, 3922.352811786052, 5237.476252374732, 5413.810516631938, 5427.042261967237, 5452.372977107604, 5479.835604931858, 4941.975290123526, 4976.925346119811, 5000.799968001275, 5025.597952163818, 5031.52200540948, 5866.943950088982, 5873.911891321591, 5904.346782900795, 5904.520101557313, 5907.08019893532, 4080.3879418087226, 4145.440365550642, 4155.355919832431, 4183.790810459464, 4187.1625674865145, 6013.594562175122, 6021.307549631804, 6033.0, 6043.244594425741, 6059.878802423927, 4330.437043184429, 4340.956590434118, 4346.282863327516, 4352.107817897323, 4352.630026447724, 4300.225959327288, 4324.783760811947, 4354.804033818423, 4379.211741886491, 4381.846635367747, 4933.925991110136, 4939.900542269521, 4948.356713627206, 4950.851474455772, 4993.300937868701, 5961.0, 5970.820875373745, 5971.373005834324, 5980.700964951822, 6019.608725432833, 1413.6748547834056, 1435.1604311477693, 1498.3631956418478, 1501.9924900375647, 1505.127026324729, 964.7057647417465, 982.8623992641036, 987.5654711926729, 990.2721499697716, 998.7103739915489, 5617.9438205618235, 5735.254416925856, 5762.279715035621, 5791.276090488689, 5806.880488621623, 6095.451409373178, 6104.145419641254, 6107.6563156105585, 6109.84725381869, 6112.969435152885, 5751.453611906946, 5766.221560089418, 5766.365699773021, 5798.362180160179, 5806.65160090396, 3120.6567277599447, 3165.8733650654176, 3212.550242965987, 3219.4687876500584, 3257.576515053028, 6086.908696369558, 6111.54163437744, 6148.063360041572, 6173.259208894871, 6197.194364732556, 5913.852153696192, 6041.2750469943885, 6041.969790151021, 6052.152698622196, 6066.726997285133, 6120.479759220427, 6150.231221097363, 6160.291566469824, 6210.658413787285, 6238.968805155945, 5715.0, 5919.822405327847, 5982.970085149546, 5990.850228744316, 5993.31076926159, 5822.854428639149, 5834.912476312774, 5862.413758624103, 5871.911921321098, 5920.79277225295, 6115.663638499747, 6135.11040899076, 6140.201773769381, 6150.846228844314, 6172.567920245539, 4130.483689538808, 4311.245532031945, 4331.783410829509, 4351.12977404514, 4352.173087113439, 5954.707437636454, 5985.862686089629, 5992.101184822312, 6010.939890601038, 6027.909581356195, 5548.0, 5655.971720141373, 5797.565182611405, 5849.590528663123, 5905.232319798571, 5817.592768506164, 5908.3500814911185, 5919.31927828288, 5922.555808314308, 5925.844460330367, 5782.421757824184, 5821.825345239649, 5826.213461182856, 5845.18167456565, 5918.822435326947, 5704.3154821421695, 5973.5519836013345, 5981.1327357532045, 5981.132735753378, 5993.910091348721, 5811.389804070512, 5833.0, 5836.241288632451, 5837.970810146092, 5849.53203743708], "value": 4479.204363611785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.5052543736479, 835.1314632781907, 896.4486840593036, 905.3526728389201, 920.6087412849539, 15821.525354239377, 17588.736168957475, 17596.384126555542, 17626.206820693067, 17632.294708211655, 15960.920195399007, 16141.43504977326, 16159.0, 16207.027578345278, 16223.26995285212, 888.2316795971486, 896.5114012863005, 899.0649724286734, 904.4030939579878, 909.727081875438, 884.0143240287098, 888.6756333938098, 889.888763904512, 890.0165317324361, 891.4294851295176, 941.8634298026782, 942.0391200975001, 943.1323182671924, 943.7168849345203, 943.9764005899839, 928.5821380378823, 935.1443429262217, 936.063936063936, 936.66748304352, 936.8454205056157, 684.6200358800863, 688.7451642892112, 689.0112688292317, 689.1351354050685, 689.2659317826499, 13413.262270575115, 13423.530176443777, 13446.663833404145, 13476.797848032189, 13597.932010339884, 13466.394012269471, 13483.123596966167, 13510.662233444144, 13582.93208533951, 13650.726985460235, 11753.88246117545, 12043.0, 12056.397180141046, 12271.0, 12314.630561083091, 3998.1921218231687, 4004.379321205218, 4044.0496483326397, 4051.113107716864, 4077.938830917539, 53464.19803702948, 67267.66366168187, 69739.65130174368, 70022.29977700209, 75202.0, 12158.513659453523, 13033.739325213442, 13037.674058148623, 13067.134313593366, 13085.08404411682, 21805.345839624835, 24667.0, 24712.25863224106, 24723.87638061798, 24763.5047299053, 9447.233189817623, 9471.573779179953, 9475.620975161055, 9547.093026162504, 9912.35569687968, 4449.199144154083, 4512.9097418052115, 4515.322701594787, 4557.703749256288, 4721.055788842245, 3918.392649139388, 3947.585503522146, 4039.7374170678813, 4043.939340909889, 4047.1298670785923, 712.2592503796097, 714.7534100735238, 723.9203687594361, 728.9599072051088, 737.7749786315118, 655.2988302516262, 689.3106893106893, 752.9345975444794, 779.9181085986003, 784.2902173532962, 10289.3311934724, 10320.793584128276, 10491.0, 10539.05027569902, 10596.258261921746, 754.3178020978468, 755.5693254844796, 757.2124990010437, 766.5247546521192, 769.6257047009925, 4598.954010459853, 4637.513061128533, 4652.302154676826, 4660.976695116503, 4668.486466488684, 104956.47521762342, 109280.0, 111254.44372778085, 115476.0, 116646.41676791562, 93783.53108234552, 97863.99999999857, 99241.50379248058, 99462.0, 101257.0, 68273.0, 81803.0, 83738.5813070943, 89822.55088724646, 94967.52516237374, 9771.0, 9800.264980126376, 9876.420121735448, 10240.436775977392, 10314.587416503557, 48633.270500941806, 48979.26531101965, 50287.49712502828, 50311.49688503068, 52754.73622631862, 69281.71217505675, 69315.0, 73265.0, 74152.0, 74819.62590187014, 12190.817137742764, 12319.63041108768, 12358.938205308916, 12699.682507937376, 12807.423665934868, 13198.736025279248, 13533.526326578523, 13557.59327220185, 13626.795598065839, 14155.716885662023, 12451.315177664954, 12813.245600490223, 12838.101332906606, 12849.678758031125, 13250.668733281746, 12738.172018818746, 12757.55348562833, 12776.0, 12821.23072615646, 12979.935100324437, 12264.938675306566, 12449.190802598165, 12530.74938501243, 12837.807432888327, 13213.075084743976, 10453.372797632166, 10560.894391056298, 10614.0, 10823.350598964087, 11021.944890275498, 10831.2959657622, 10858.67423977282, 11053.11575073976, 11222.831657525307, 11257.943710281725], "value": 7753.25141508345, "unit": "score"}], "name": "dromaeo_css", "value": 7807.606418061111, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 109.0, 112.0, 109.0, 112.0, 108.0, 110.0, 112.0, 108.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 124.0, 172.0, 123.0, 123.0, 146.0, 124.0, 153.0, 123.0], "value": 136.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 163.0, 160.0, 159.0, 161.0, 160.0, 161.0, 161.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 112.0, 74.0, 75.0, 75.0, 75.0, 97.0, 75.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 116.0, 84.0, 84.0, 83.0, 119.0, 83.0, 85.0, 129.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 148.0, 146.0, 145.0, 146.0, 146.0, 145.0, 146.0], "value": 145.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 57.0, 52.0, 51.0, 51.0, 52.0, 52.0, 50.0, 51.0, 52.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 77.0, 81.0, 86.0, 84.0, 78.0, 78.0, 81.0, 116.0, 76.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 117.0, 121.0, 118.0, 115.0, 117.0, 115.0, 117.0, 122.0, 119.0], "value": 118.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 161.0, 159.0, 154.0, 156.0, 152.0, 157.0, 161.0, 157.0, 154.0], "value": 156.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 60.0, 61.0, 60.0, 61.0, 63.0, 61.0, 61.0, 60.0, 61.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1471.3, "alertThreshold": 2.0}]} 11:44:46 INFO - Return code: 0 11:44:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:44:46 ERROR - # TBPL SUCCESS # 11:44:46 INFO - Running post-action listener: _resource_record_post_action 11:44:46 INFO - ##### 11:44:46 INFO - ##### Finished run-tests step (success) 11:44:46 INFO - ##### 11:44:46 INFO - Running post-run listener: _resource_record_post_run 11:44:46 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 4096; Write bytes: 455491584; Read time: 8; Write time: 578776 11:44:46 INFO - TinderboxPrint: CPU usage
13.1% 11:44:46 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 11:44:46 INFO - TinderboxPrint: I/O write bytes / time
455,491,584 / 578,776 11:44:46 INFO - TinderboxPrint: CPU idle
8,244.4 (86.9%) 11:44:46 INFO - TinderboxPrint: CPU user
1,193.8 (12.6%) 11:44:46 INFO - TinderboxPrint: Swap in / out
0 / 0 11:44:46 INFO - install - Wall time: 13s; CPU: 15.0%; Read bytes: 0; Write bytes: 235663360; Read time: 0; Write time: 408892 11:44:46 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219828224; Read time: 8; Write time: 169884 11:44:46 INFO - Running post-run listener: _upload_blobber_files 11:44:46 INFO - Blob upload gear active. 11:44:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:44:47 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 11:44:47 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:44:47 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:44:47 INFO - (blobuploader) - INFO - Open directory for files ... 11:44:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:44:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:48 INFO - (blobuploader) - INFO - Done attempting. 11:44:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:44:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:44:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:44:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:44:49 INFO - (blobuploader) - INFO - Done attempting. 11:44:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:44:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:44:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:45:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:45:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:45:00 INFO - (blobuploader) - INFO - Done attempting. 11:45:00 INFO - (blobuploader) - INFO - Iteration through files over. 11:45:00 INFO - Return code: 0 11:45:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:45:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:45:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bcf06974b8c3df38d2c1759a811e9096ab37fced2cb3b8ad386c07230a3d2c4e3bbfef529a2bfe363a4345882283aadfbd52b294144513f39d8a8701691e7cf4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae672a634f6a6c9ab623ace9dd0c395b4480b542ddb513587659df03c7299513d588d7fafb345adaaab6c0bec6c367785236732407dd28344c777fc52b12214", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b5f451816bc079430a4f73e5859add6f61d448a9ed9bd9291d6902bd979e0578d902102699f24fde555da0b3acb73a542ad6ec0080209fdddb4acbb350f7780"} 11:45:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:45:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:45:00 INFO - Contents: 11:45:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bcf06974b8c3df38d2c1759a811e9096ab37fced2cb3b8ad386c07230a3d2c4e3bbfef529a2bfe363a4345882283aadfbd52b294144513f39d8a8701691e7cf4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae672a634f6a6c9ab623ace9dd0c395b4480b542ddb513587659df03c7299513d588d7fafb345adaaab6c0bec6c367785236732407dd28344c777fc52b12214", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b5f451816bc079430a4f73e5859add6f61d448a9ed9bd9291d6902bd979e0578d902102699f24fde555da0b3acb73a542ad6ec0080209fdddb4acbb350f7780"} 11:45:00 INFO - Running post-run listener: copy_logs_to_upload_dir 11:45:00 INFO - Copying logs to upload dir... 11:45:00 INFO - mkdir: /builds/slave/test/build/upload/logs 11:45:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1242.850851 ========= master_lag: 1.57 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 44 secs) (at 2016-08-01 11:45:00.071558) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 11:45:00.079331) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bcf06974b8c3df38d2c1759a811e9096ab37fced2cb3b8ad386c07230a3d2c4e3bbfef529a2bfe363a4345882283aadfbd52b294144513f39d8a8701691e7cf4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae672a634f6a6c9ab623ace9dd0c395b4480b542ddb513587659df03c7299513d588d7fafb345adaaab6c0bec6c367785236732407dd28344c777fc52b12214", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b5f451816bc079430a4f73e5859add6f61d448a9ed9bd9291d6902bd979e0578d902102699f24fde555da0b3acb73a542ad6ec0080209fdddb4acbb350f7780"} build_url:https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010051 build_url: 'https://queue.taskcluster.net/v1/task/cet431kUTUGquqQcQ5vlmg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bcf06974b8c3df38d2c1759a811e9096ab37fced2cb3b8ad386c07230a3d2c4e3bbfef529a2bfe363a4345882283aadfbd52b294144513f39d8a8701691e7cf4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ae672a634f6a6c9ab623ace9dd0c395b4480b542ddb513587659df03c7299513d588d7fafb345adaaab6c0bec6c367785236732407dd28344c777fc52b12214", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8b5f451816bc079430a4f73e5859add6f61d448a9ed9bd9291d6902bd979e0578d902102699f24fde555da0b3acb73a542ad6ec0080209fdddb4acbb350f7780"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 11:45:00.335768) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:45:00.336072) ========= 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=d6bbd613908ccac0c6c9cb47000001f7-1470074970.290647-2031291516 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004410 ========= master_lag: 0.01 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 11:45:00.355173) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 11:45:00.355458) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 11:45:00.359565) ========= ========= Total master_lag: 1.91 =========