builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1467069013.24 results: success (0) buildid: 20160627133103 builduid: 56b7278b78074185aadebbc140dc7a28 revision: 1d84194ace3538ad91d6cffb6fa3ec6a3a5037be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.236200) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.236639) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.236951) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.285828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.286165) ========= 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=0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-27 16:10:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-06-27 16:10:13 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078210 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.406119) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.406446) ========= 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=0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017301 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:13.459874) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 16:10:13.463289) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1d84194ace3538ad91d6cffb6fa3ec6a3a5037be --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1d84194ace3538ad91d6cffb6fa3ec6a3a5037be --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=0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-27 16:10:14,106 truncating revision to first 12 chars 2016-06-27 16:10:14,106 Setting DEBUG logging. 2016-06-27 16:10:14,106 attempt 1/10 2016-06-27 16:10:14,106 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1d84194ace35?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-27 16:10:14,792 unpacking tar archive at: mozilla-central-1d84194ace35/testing/mozharness/ program finished with exit code 0 elapsedTime=1.029378 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-27 16:10:14.515062) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:14.515479) ========= script_repo_revision: 1d84194ace3538ad91d6cffb6fa3ec6a3a5037be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:14.515902) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:14.516227) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-27 16:10:14.529732) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-27 16:10:14.530050) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:10:15 INFO - MultiFileLogger online at 20160627 16:10:15 in /builds/slave/test 16:10:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:10:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:10:15 INFO - {'append_to_log': False, 16:10:15 INFO - 'base_work_dir': '/builds/slave/test', 16:10:15 INFO - 'blob_upload_branch': 'Firefox', 16:10:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:10:15 INFO - 'branch': 'Firefox', 16:10:15 INFO - 'buildbot_json_path': 'buildprops.json', 16:10:15 INFO - 'config_files': ('talos/linux_config.py',), 16:10:15 INFO - 'default_actions': ('clobber', 16:10:15 INFO - 'read-buildbot-config', 16:10:15 INFO - 'download-and-extract', 16:10:15 INFO - 'populate-webroot', 16:10:15 INFO - 'create-virtualenv', 16:10:15 INFO - 'install', 16:10:15 INFO - 'run-tests'), 16:10:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:10:15 INFO - 'download_minidump_stackwalk': True, 16:10:15 INFO - 'download_symbols': 'ondemand', 16:10:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:10:15 INFO - 'tooltool.py': '/tools/tooltool.py', 16:10:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:10:15 INFO - '/tools/misc-python/virtualenv.py')}, 16:10:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:10:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:10:15 INFO - 'installer_path': 'installer.exe', 16:10:15 INFO - 'log_level': 'info', 16:10:15 INFO - 'log_name': 'talos', 16:10:15 INFO - 'log_to_console': True, 16:10:15 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:10:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:10:15 INFO - 'opt_config_files': (), 16:10:15 INFO - 'pip_index': False, 16:10:15 INFO - 'sps_profile': False, 16:10:15 INFO - 'sps_profile_interval': 0, 16:10:15 INFO - 'suite': 'dromaeojs', 16:10:15 INFO - 'system_bits': '32', 16:10:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:10:15 INFO - 'title': 'talos-linux64-ix-058', 16:10:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:10:15 INFO - 'use_talos_json': True, 16:10:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:10:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:10:15 INFO - 'work_dir': 'build'} 16:10:15 INFO - ##### 16:10:15 INFO - ##### Running clobber step. 16:10:15 INFO - ##### 16:10:15 INFO - Running pre-action listener: _resource_record_pre_action 16:10:15 INFO - Running main action method: clobber 16:10:15 INFO - rmtree: /builds/slave/test/build 16:10:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:10:16 INFO - Running post-action listener: _resource_record_post_action 16:10:16 INFO - ##### 16:10:16 INFO - ##### Finished clobber step (success) 16:10:16 INFO - ##### 16:10:16 INFO - ##### 16:10:16 INFO - ##### Running read-buildbot-config step. 16:10:16 INFO - ##### 16:10:16 INFO - Running pre-action listener: _resource_record_pre_action 16:10:16 INFO - Running main action method: read_buildbot_config 16:10:16 INFO - Using buildbot properties: 16:10:16 INFO - { 16:10:16 INFO - "project": "", 16:10:16 INFO - "product": "firefox", 16:10:16 INFO - "script_repo_revision": "production", 16:10:16 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 16:10:16 INFO - "repository": "", 16:10:16 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 16:10:16 INFO - "buildid": "20160627133103", 16:10:16 INFO - "pgo_build": "True", 16:10:16 INFO - "basedir": "/builds/slave/test", 16:10:16 INFO - "buildnumber": 792, 16:10:16 INFO - "slavename": "talos-linux64-ix-058", 16:10:16 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:10:16 INFO - "platform": "ubuntu64_hw", 16:10:16 INFO - "scheduler": "tests-mozilla-central-linux64-pgo-talos", 16:10:16 INFO - "branch": "mozilla-central", 16:10:16 INFO - "repo_path": "mozilla-central", 16:10:16 INFO - "revision": "1d84194ace3538ad91d6cffb6fa3ec6a3a5037be", 16:10:16 INFO - "stage_platform": "linux64-pgo", 16:10:16 INFO - "builduid": "56b7278b78074185aadebbc140dc7a28", 16:10:16 INFO - "slavebuilddir": "test" 16:10:16 INFO - } 16:10:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 16:10:16 INFO - Running post-action listener: _resource_record_post_action 16:10:16 INFO - ##### 16:10:16 INFO - ##### Finished read-buildbot-config step (success) 16:10:16 INFO - ##### 16:10:16 INFO - ##### 16:10:16 INFO - ##### Running download-and-extract step. 16:10:16 INFO - ##### 16:10:16 INFO - Running pre-action listener: _resource_record_pre_action 16:10:16 INFO - Running main action method: download_and_extract 16:10:16 INFO - mkdir: /builds/slave/test/build/tests 16:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:16 INFO - https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:10:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:10:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:10:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:10:16 INFO - Downloaded 1361 bytes. 16:10:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 16:10:16 INFO - Using the following test package requirements: 16:10:16 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:10:16 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 16:10:16 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 16:10:16 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'jsshell-linux-x86_64.zip'], 16:10:16 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 16:10:16 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 16:10:16 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 16:10:16 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 16:10:16 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 16:10:16 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 16:10:16 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 16:10:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:16 INFO - https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:10:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:10:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:10:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:10:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:10:18 INFO - Downloaded 24279529 bytes. 16:10:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:10:19 INFO - Return code: 0 16:10:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:10:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:19 INFO - https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:10:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:10:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:10:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 16:10:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:10:23 INFO - Downloaded 24279529 bytes. 16:10:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:10:24 INFO - Return code: 0 16:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:24 INFO - https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:10:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:10:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:10:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 16:10:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:10:26 INFO - Downloaded 14405240 bytes. 16:10:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:10:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:10:26 INFO - Return code: 0 16:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:26 INFO - https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:10:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:10:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:10:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:10:31 INFO - Downloaded 60186570 bytes. 16:10:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:10:31 INFO - mkdir: /builds/slave/test/properties 16:10:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:10:31 INFO - Writing to file /builds/slave/test/properties/build_url 16:10:31 INFO - Contents: 16:10:31 INFO - build_url:https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 16:10:31 INFO - Running post-action listener: _resource_record_post_action 16:10:31 INFO - Running post-action listener: set_extra_try_arguments 16:10:31 INFO - ##### 16:10:31 INFO - ##### Finished download-and-extract step (success) 16:10:31 INFO - ##### 16:10:31 INFO - ##### 16:10:31 INFO - ##### Running populate-webroot step. 16:10:31 INFO - ##### 16:10:31 INFO - Running pre-action listener: _resource_record_pre_action 16:10:31 INFO - Running main action method: populate_webroot 16:10:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:10:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:10:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:10:31 INFO - '--tppagecycles', 16:10:31 INFO - '7'], 16:10:31 INFO - 'tests': ['tsvgm']}}, 16:10:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:10:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:10:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:10:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:10:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:10:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['damp', 'tps']}, 16:10:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'tests': ['damp', 'tps']}, 16:10:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['dromaeo_dom']}, 16:10:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:10:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['basic_compositor_video']}, 16:10:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:10:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other-e10s': {'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['a11yr', 16:10:31 INFO - 'ts_paint', 16:10:31 INFO - 'tpaint', 16:10:31 INFO - 'sessionrestore', 16:10:31 INFO - 'sessionrestore_no_auto_restore', 16:10:31 INFO - 'tabpaint']}, 16:10:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['tsvgx', 16:10:31 INFO - 'tsvgr_opacity', 16:10:31 INFO - 'tart', 16:10:31 INFO - 'tscrollx', 16:10:31 INFO - 'cart']}, 16:10:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:10:31 INFO - 'tsvgr_opacity', 16:10:31 INFO - 'tart', 16:10:31 INFO - 'tscrollx', 16:10:31 INFO - 'cart']}, 16:10:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--disable-e10s'], 16:10:31 INFO - 'tests': ['tp5o']}, 16:10:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'tests': ['tp5o']}, 16:10:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--disable-e10s', 16:10:31 INFO - '--xperf_path', 16:10:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:31 INFO - 'tests': ['tp5n']}, 16:10:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:10:31 INFO - 'talos_options': ['--xperf_path', 16:10:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:10:31 INFO - 'tests': ['tp5n']}}, 16:10:31 INFO - 'talos.zip': {'path': '', 16:10:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:10:31 INFO - Running post-action listener: _resource_record_post_action 16:10:31 INFO - ##### 16:10:31 INFO - ##### Finished populate-webroot step (success) 16:10:31 INFO - ##### 16:10:31 INFO - ##### 16:10:31 INFO - ##### Running create-virtualenv step. 16:10:31 INFO - ##### 16:10:31 INFO - Running pre-action listener: _resource_record_pre_action 16:10:31 INFO - Running main action method: create_virtualenv 16:10:31 INFO - Creating virtualenv /builds/slave/test/build/venv 16:10:31 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:10:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:10:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:10:31 INFO - Using real prefix '/usr' 16:10:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:10:32 INFO - Installing distribute.............................................................................................................................................................................................done. 16:10:34 INFO - Installing pip.................done. 16:10:34 INFO - Return code: 0 16:10:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:10:34 INFO - Using env: {'DISPLAY': ':0', 16:10:34 INFO - 'HOME': '/home/cltbld', 16:10:34 INFO - 'LANG': 'en_US.UTF-8', 16:10:34 INFO - 'LANGUAGE': 'en_US:en', 16:10:34 INFO - 'LOGNAME': 'cltbld', 16:10:34 INFO - 'MAIL': '/var/mail/cltbld', 16:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:34 INFO - 'MOZ_NO_REMOTE': '1', 16:10:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:34 INFO - 'NO_EM_RESTART': '1', 16:10:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:34 INFO - 'PWD': '/builds/slave/test', 16:10:34 INFO - 'SHELL': '/bin/bash', 16:10:34 INFO - 'SHLVL': '1', 16:10:34 INFO - 'TERM': 'linux', 16:10:34 INFO - 'TMOUT': '86400', 16:10:34 INFO - 'USER': 'cltbld', 16:10:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:34 INFO - '_': '/tools/buildbot/bin/python'} 16:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:10:34 INFO - Cleaning up... 16:10:34 INFO - Return code: 0 16:10:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:10:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:10:34 INFO - Using env: {'DISPLAY': ':0', 16:10:34 INFO - 'HOME': '/home/cltbld', 16:10:34 INFO - 'LANG': 'en_US.UTF-8', 16:10:34 INFO - 'LANGUAGE': 'en_US:en', 16:10:34 INFO - 'LOGNAME': 'cltbld', 16:10:34 INFO - 'MAIL': '/var/mail/cltbld', 16:10:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:34 INFO - 'MOZ_NO_REMOTE': '1', 16:10:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:34 INFO - 'NO_EM_RESTART': '1', 16:10:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:34 INFO - 'PWD': '/builds/slave/test', 16:10:34 INFO - 'SHELL': '/bin/bash', 16:10:34 INFO - 'SHLVL': '1', 16:10:34 INFO - 'TERM': 'linux', 16:10:34 INFO - 'TMOUT': '86400', 16:10:34 INFO - 'USER': 'cltbld', 16:10:34 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:34 INFO - '_': '/tools/buildbot/bin/python'} 16:10:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:34 INFO - Downloading/unpacking psutil>=3.1.1 16:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:36 INFO - Installing collected packages: psutil 16:10:36 INFO - Running setup.py install for psutil 16:10:36 INFO - building 'psutil._psutil_linux' extension 16:10:36 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:10:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:10:37 INFO - building 'psutil._psutil_posix' extension 16:10:37 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:10:37 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 16:10:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:37 INFO - Successfully installed psutil 16:10:37 INFO - Cleaning up... 16:10:37 INFO - Return code: 0 16:10:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:10:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:10:37 INFO - Using env: {'DISPLAY': ':0', 16:10:37 INFO - 'HOME': '/home/cltbld', 16:10:37 INFO - 'LANG': 'en_US.UTF-8', 16:10:37 INFO - 'LANGUAGE': 'en_US:en', 16:10:37 INFO - 'LOGNAME': 'cltbld', 16:10:37 INFO - 'MAIL': '/var/mail/cltbld', 16:10:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:37 INFO - 'MOZ_NO_REMOTE': '1', 16:10:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:37 INFO - 'NO_EM_RESTART': '1', 16:10:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:37 INFO - 'PWD': '/builds/slave/test', 16:10:37 INFO - 'SHELL': '/bin/bash', 16:10:37 INFO - 'SHLVL': '1', 16:10:37 INFO - 'TERM': 'linux', 16:10:37 INFO - 'TMOUT': '86400', 16:10:37 INFO - 'USER': 'cltbld', 16:10:37 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:37 INFO - '_': '/tools/buildbot/bin/python'} 16:10:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:10:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:10:39 INFO - Installing collected packages: mozsystemmonitor 16:10:39 INFO - Running setup.py install for mozsystemmonitor 16:10:39 INFO - Successfully installed mozsystemmonitor 16:10:39 INFO - Cleaning up... 16:10:39 INFO - Return code: 0 16:10:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:10:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:10:39 INFO - Using env: {'DISPLAY': ':0', 16:10:39 INFO - 'HOME': '/home/cltbld', 16:10:39 INFO - 'LANG': 'en_US.UTF-8', 16:10:39 INFO - 'LANGUAGE': 'en_US:en', 16:10:39 INFO - 'LOGNAME': 'cltbld', 16:10:39 INFO - 'MAIL': '/var/mail/cltbld', 16:10:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:39 INFO - 'MOZ_NO_REMOTE': '1', 16:10:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:39 INFO - 'NO_EM_RESTART': '1', 16:10:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:39 INFO - 'PWD': '/builds/slave/test', 16:10:39 INFO - 'SHELL': '/bin/bash', 16:10:39 INFO - 'SHLVL': '1', 16:10:39 INFO - 'TERM': 'linux', 16:10:39 INFO - 'TMOUT': '86400', 16:10:39 INFO - 'USER': 'cltbld', 16:10:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:39 INFO - '_': '/tools/buildbot/bin/python'} 16:10:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:10:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:42 INFO - Downloading functools32-3.2.3-2.tar.gz 16:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:10:42 INFO - warning: no files found matching '*.txt' 16:10:42 INFO - no previously-included directories found matching 'build' 16:10:42 INFO - no previously-included directories found matching 'dist' 16:10:42 INFO - no previously-included directories found matching '.git*' 16:10:42 INFO - Installing collected packages: jsonschema, functools32 16:10:42 INFO - Running setup.py install for functools32 16:10:42 INFO - warning: no files found matching '*.txt' 16:10:42 INFO - no previously-included directories found matching 'build' 16:10:42 INFO - no previously-included directories found matching 'dist' 16:10:42 INFO - no previously-included directories found matching '.git*' 16:10:42 INFO - Successfully installed jsonschema functools32 16:10:42 INFO - Cleaning up... 16:10:42 INFO - Return code: 0 16:10:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:10:42 INFO - Using env: {'DISPLAY': ':0', 16:10:42 INFO - 'HOME': '/home/cltbld', 16:10:42 INFO - 'LANG': 'en_US.UTF-8', 16:10:42 INFO - 'LANGUAGE': 'en_US:en', 16:10:42 INFO - 'LOGNAME': 'cltbld', 16:10:42 INFO - 'MAIL': '/var/mail/cltbld', 16:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:42 INFO - 'MOZ_NO_REMOTE': '1', 16:10:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:42 INFO - 'NO_EM_RESTART': '1', 16:10:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:42 INFO - 'PWD': '/builds/slave/test', 16:10:42 INFO - 'SHELL': '/bin/bash', 16:10:42 INFO - 'SHLVL': '1', 16:10:42 INFO - 'TERM': 'linux', 16:10:42 INFO - 'TMOUT': '86400', 16:10:42 INFO - 'USER': 'cltbld', 16:10:42 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:42 INFO - '_': '/tools/buildbot/bin/python'} 16:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:42 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:44 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:10:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:45 INFO - Downloading docopt-0.6.1.tar.gz 16:10:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:45 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:45 INFO - Running setup.py install for blobuploader 16:10:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:10:45 INFO - Running setup.py install for requests 16:10:45 INFO - Running setup.py install for docopt 16:10:45 INFO - Successfully installed blobuploader requests docopt 16:10:45 INFO - Cleaning up... 16:10:45 INFO - Return code: 0 16:10:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:10:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:10:45 INFO - Using env: {'DISPLAY': ':0', 16:10:45 INFO - 'HOME': '/home/cltbld', 16:10:45 INFO - 'LANG': 'en_US.UTF-8', 16:10:45 INFO - 'LANGUAGE': 'en_US:en', 16:10:45 INFO - 'LOGNAME': 'cltbld', 16:10:45 INFO - 'MAIL': '/var/mail/cltbld', 16:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:45 INFO - 'MOZ_NO_REMOTE': '1', 16:10:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:45 INFO - 'NO_EM_RESTART': '1', 16:10:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:45 INFO - 'PWD': '/builds/slave/test', 16:10:45 INFO - 'SHELL': '/bin/bash', 16:10:45 INFO - 'SHLVL': '1', 16:10:45 INFO - 'TERM': 'linux', 16:10:45 INFO - 'TMOUT': '86400', 16:10:45 INFO - 'USER': 'cltbld', 16:10:45 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:45 INFO - '_': '/tools/buildbot/bin/python'} 16:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:46 INFO - Running setup.py (path:/tmp/pip-CpculD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:46 INFO - Running setup.py (path:/tmp/pip-pzOqM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:46 INFO - Running setup.py (path:/tmp/pip-Rt91ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:46 INFO - Running setup.py (path:/tmp/pip-8_w1wD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:46 INFO - Running setup.py (path:/tmp/pip-DscBxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:46 INFO - Running setup.py (path:/tmp/pip-NPn_OF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:46 INFO - Running setup.py (path:/tmp/pip-NVvPEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:46 INFO - Running setup.py (path:/tmp/pip-rWXLw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:46 INFO - Running setup.py (path:/tmp/pip-sL8ZPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:46 INFO - Running setup.py (path:/tmp/pip-UoDjO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:46 INFO - Running setup.py (path:/tmp/pip-HHEyDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:46 INFO - Running setup.py (path:/tmp/pip-nl7jKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:47 INFO - Running setup.py (path:/tmp/pip-m6Q3sQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:47 INFO - Running setup.py (path:/tmp/pip-HJwRsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:47 INFO - Running setup.py (path:/tmp/pip-V6yTkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:47 INFO - Running setup.py (path:/tmp/pip-rVCmEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:47 INFO - Running setup.py (path:/tmp/pip-HhUkNc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:47 INFO - Running setup.py install for manifestparser 16:10:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:10:47 INFO - Running setup.py install for mozcrash 16:10:47 INFO - Running setup.py install for mozdebug 16:10:47 INFO - Running setup.py install for mozdevice 16:10:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:10:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:10:47 INFO - Running setup.py install for mozfile 16:10:47 INFO - Running setup.py install for mozhttpd 16:10:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for mozinfo 16:10:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for mozInstall 16:10:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for mozleak 16:10:48 INFO - Running setup.py install for mozlog 16:10:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for moznetwork 16:10:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for mozprocess 16:10:48 INFO - Running setup.py install for mozprofile 16:10:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for mozrunner 16:10:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:10:48 INFO - Running setup.py install for mozscreenshot 16:10:49 INFO - Running setup.py install for moztest 16:10:49 INFO - Running setup.py install for mozversion 16:10:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:10:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:49 INFO - Cleaning up... 16:10:49 INFO - Return code: 0 16:10:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:10:49 INFO - Using env: {'DISPLAY': ':0', 16:10:49 INFO - 'HOME': '/home/cltbld', 16:10:49 INFO - 'LANG': 'en_US.UTF-8', 16:10:49 INFO - 'LANGUAGE': 'en_US:en', 16:10:49 INFO - 'LOGNAME': 'cltbld', 16:10:49 INFO - 'MAIL': '/var/mail/cltbld', 16:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:49 INFO - 'MOZ_NO_REMOTE': '1', 16:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:49 INFO - 'NO_EM_RESTART': '1', 16:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:49 INFO - 'PWD': '/builds/slave/test', 16:10:49 INFO - 'SHELL': '/bin/bash', 16:10:49 INFO - 'SHLVL': '1', 16:10:49 INFO - 'TERM': 'linux', 16:10:49 INFO - 'TMOUT': '86400', 16:10:49 INFO - 'USER': 'cltbld', 16:10:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:49 INFO - '_': '/tools/buildbot/bin/python'} 16:10:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:49 INFO - Running setup.py (path:/tmp/pip-xlsTGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:49 INFO - Running setup.py (path:/tmp/pip-sFrZ82-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:49 INFO - Running setup.py (path:/tmp/pip-gl7izZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:49 INFO - Running setup.py (path:/tmp/pip-N23KS8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:49 INFO - Running setup.py (path:/tmp/pip-IwTcvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:49 INFO - Running setup.py (path:/tmp/pip-7WQgYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:49 INFO - Running setup.py (path:/tmp/pip-KlwCeu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:10:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:49 INFO - Running setup.py (path:/tmp/pip-JNthvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:50 INFO - Running setup.py (path:/tmp/pip-TWlb0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:50 INFO - Running setup.py (path:/tmp/pip-OB_7D6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:50 INFO - Running setup.py (path:/tmp/pip-l5PjQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:50 INFO - Running setup.py (path:/tmp/pip-PhU_Cc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:50 INFO - Running setup.py (path:/tmp/pip-Po9Ob0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:50 INFO - Running setup.py (path:/tmp/pip-Hb6lHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:50 INFO - Running setup.py (path:/tmp/pip-hBwlWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:50 INFO - Running setup.py (path:/tmp/pip-HCEuuR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:50 INFO - Running setup.py (path:/tmp/pip-v3mcew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:10:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:52 INFO - Downloading blessings-1.6.tar.gz 16:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:52 INFO - Installing collected packages: blessings 16:10:52 INFO - Running setup.py install for blessings 16:10:52 INFO - Successfully installed blessings 16:10:52 INFO - Cleaning up... 16:10:52 INFO - Return code: 0 16:10:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:10:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:53 INFO - Reading from file tmpfile_stdout 16:10:53 INFO - Current package versions: 16:10:53 INFO - argparse == 1.2.1 16:10:53 INFO - blessings == 1.6 16:10:53 INFO - blobuploader == 1.2.4 16:10:53 INFO - docopt == 0.6.1 16:10:53 INFO - functools32 == 3.2.3-2 16:10:53 INFO - jsonschema == 2.5.1 16:10:53 INFO - manifestparser == 1.1 16:10:53 INFO - mozInstall == 1.12 16:10:53 INFO - mozcrash == 0.17 16:10:53 INFO - mozdebug == 0.1 16:10:53 INFO - mozdevice == 0.48 16:10:53 INFO - mozfile == 1.2 16:10:53 INFO - mozhttpd == 0.7 16:10:53 INFO - mozinfo == 0.9 16:10:53 INFO - mozleak == 0.1 16:10:53 INFO - mozlog == 3.2 16:10:53 INFO - moznetwork == 0.27 16:10:53 INFO - mozprocess == 0.22 16:10:53 INFO - mozprofile == 0.28 16:10:53 INFO - mozrunner == 6.11 16:10:53 INFO - mozscreenshot == 0.1 16:10:53 INFO - mozsystemmonitor == 0.3 16:10:53 INFO - moztest == 0.7 16:10:53 INFO - mozversion == 1.4 16:10:53 INFO - psutil == 3.1.1 16:10:53 INFO - requests == 1.2.3 16:10:53 INFO - wsgiref == 0.1.2 16:10:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:10:53 INFO - Using env: {'DISPLAY': ':0', 16:10:53 INFO - 'HOME': '/home/cltbld', 16:10:53 INFO - 'LANG': 'en_US.UTF-8', 16:10:53 INFO - 'LANGUAGE': 'en_US:en', 16:10:53 INFO - 'LOGNAME': 'cltbld', 16:10:53 INFO - 'MAIL': '/var/mail/cltbld', 16:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:53 INFO - 'MOZ_NO_REMOTE': '1', 16:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:53 INFO - 'NO_EM_RESTART': '1', 16:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:53 INFO - 'PWD': '/builds/slave/test', 16:10:53 INFO - 'SHELL': '/bin/bash', 16:10:53 INFO - 'SHLVL': '1', 16:10:53 INFO - 'TERM': 'linux', 16:10:53 INFO - 'TMOUT': '86400', 16:10:53 INFO - 'USER': 'cltbld', 16:10:53 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:53 INFO - '_': '/tools/buildbot/bin/python'} 16:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:10:53 INFO - Cleaning up... 16:10:53 INFO - Return code: 0 16:10:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:10:53 INFO - Using env: {'DISPLAY': ':0', 16:10:53 INFO - 'HOME': '/home/cltbld', 16:10:53 INFO - 'LANG': 'en_US.UTF-8', 16:10:53 INFO - 'LANGUAGE': 'en_US:en', 16:10:53 INFO - 'LOGNAME': 'cltbld', 16:10:53 INFO - 'MAIL': '/var/mail/cltbld', 16:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:53 INFO - 'MOZ_NO_REMOTE': '1', 16:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:53 INFO - 'NO_EM_RESTART': '1', 16:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:53 INFO - 'PWD': '/builds/slave/test', 16:10:53 INFO - 'SHELL': '/bin/bash', 16:10:53 INFO - 'SHLVL': '1', 16:10:53 INFO - 'TERM': 'linux', 16:10:53 INFO - 'TMOUT': '86400', 16:10:53 INFO - 'USER': 'cltbld', 16:10:53 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:53 INFO - '_': '/tools/buildbot/bin/python'} 16:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:10:53 INFO - Cleaning up... 16:10:53 INFO - Return code: 0 16:10:53 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f1809cd8f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15708a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x163b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, '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': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:10:53 INFO - Using env: {'DISPLAY': ':0', 16:10:53 INFO - 'HOME': '/home/cltbld', 16:10:53 INFO - 'LANG': 'en_US.UTF-8', 16:10:53 INFO - 'LANGUAGE': 'en_US:en', 16:10:53 INFO - 'LOGNAME': 'cltbld', 16:10:53 INFO - 'MAIL': '/var/mail/cltbld', 16:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:53 INFO - 'MOZ_NO_REMOTE': '1', 16:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:10:53 INFO - 'NO_EM_RESTART': '1', 16:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:53 INFO - 'PWD': '/builds/slave/test', 16:10:53 INFO - 'SHELL': '/bin/bash', 16:10:53 INFO - 'SHLVL': '1', 16:10:53 INFO - 'TERM': 'linux', 16:10:53 INFO - 'TMOUT': '86400', 16:10:53 INFO - 'USER': 'cltbld', 16:10:53 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:53 INFO - '_': '/tools/buildbot/bin/python'} 16:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:53 INFO - Downloading/unpacking flake8 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:55 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:10:55 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10:55 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:10:55 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10: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 16:10:56 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:10:56 INFO - no previously-included directories found matching 'docs/_build' 16:10:56 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:10:56 INFO - Running setup.py install for pep8 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:10:56 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:10:56 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:10:56 INFO - no previously-included directories found matching 'docs/_build' 16:10:56 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:10:56 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:10:56 INFO - Cleaning up... 16:10:56 INFO - Return code: 0 16:10:56 INFO - Running post-action listener: _resource_record_post_action 16:10:56 INFO - Running post-action listener: _start_resource_monitoring 16:10:56 INFO - Starting resource monitoring. 16:10:56 INFO - ##### 16:10:56 INFO - ##### Finished create-virtualenv step (success) 16:10:56 INFO - ##### 16:10:56 INFO - ##### 16:10:56 INFO - ##### Running install step. 16:10:56 INFO - ##### 16:10:56 INFO - Running pre-action listener: _resource_record_pre_action 16:10:56 INFO - Running main action method: install 16:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:56 INFO - Reading from file tmpfile_stdout 16:10:56 INFO - Detecting whether we're running mozinstall >=1.0... 16:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:10:56 INFO - Reading from file tmpfile_stdout 16:10:56 INFO - Output received: 16:10:56 INFO - Usage: mozinstall [options] installer 16:10:56 INFO - Options: 16:10:56 INFO - -h, --help show this help message and exit 16:10:56 INFO - -d DEST, --destination=DEST 16:10:56 INFO - Directory to install application into. [default: 16:10:56 INFO - "/builds/slave/test"] 16:10:56 INFO - --app=APP Application being installed. [default: firefox] 16:10:56 INFO - mkdir: /builds/slave/test/build/application 16:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:11:13 INFO - Reading from file tmpfile_stdout 16:11:13 INFO - Output received: 16:11:13 INFO - /builds/slave/test/build/application/firefox/firefox 16:11:14 INFO - Running post-action listener: _resource_record_post_action 16:11:14 INFO - ##### 16:11:14 INFO - ##### Finished install step (success) 16:11:14 INFO - ##### 16:11:14 INFO - ##### 16:11:14 INFO - ##### Running run-tests step. 16:11:14 INFO - ##### 16:11:14 INFO - Running pre-action listener: _resource_record_pre_action 16:11:14 INFO - Running main action method: run_tests 16:11:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:11:14 INFO - Python 2.7.3 16:11:14 INFO - Return code: 0 16:11:14 INFO - grabbing minidump binary from tooltool 16:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:11:14 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 0x16ca6f0>, '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 0x16cb5f0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16cbaa0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:11:14 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:11:14 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:11:14 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:11:14 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:11:14 INFO - Return code: 0 16:11:14 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:11:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:11:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:11:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:11:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:11:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:11:15 INFO - Return code: 0 16:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:11:15 INFO - Using env: {'DISPLAY': ':0', 16:11:15 INFO - 'HOME': '/home/cltbld', 16:11:15 INFO - 'LANG': 'en_US.UTF-8', 16:11:15 INFO - 'LANGUAGE': 'en_US:en', 16:11:15 INFO - 'LOGNAME': 'cltbld', 16:11:15 INFO - 'MAIL': '/var/mail/cltbld', 16:11:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:11:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:11:15 INFO - 'MOZ_NO_REMOTE': '1', 16:11:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:11:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:11:15 INFO - 'NO_EM_RESTART': '1', 16:11:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:11:15 INFO - 'PWD': '/builds/slave/test', 16:11:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:11:15 INFO - 'SHELL': '/bin/bash', 16:11:15 INFO - 'SHLVL': '1', 16:11:15 INFO - 'TERM': 'linux', 16:11:15 INFO - 'TMOUT': '86400', 16:11:15 INFO - 'USER': 'cltbld', 16:11:15 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558', 16:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:11:15 INFO - '_': '/tools/buildbot/bin/python'} 16:11:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:11:15 INFO - mozversion application_buildid: 20160627133103 16:11:15 INFO - mozversion application_changeset: 1d84194ace3538ad91d6cffb6fa3ec6a3a5037be 16:11:15 INFO - mozversion application_display_name: Nightly 16:11:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:11:15 INFO - mozversion application_name: Firefox 16:11:15 INFO - mozversion application_remotingname: firefox 16:11:15 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:11:15 INFO - mozversion application_vendor: Mozilla 16:11:15 INFO - mozversion application_version: 50.0a1 16:11:15 INFO - mozversion platform_buildid: 20160627133103 16:11:15 INFO - mozversion platform_changeset: 1d84194ace3538ad91d6cffb6fa3ec6a3a5037be 16:11:15 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:11:15 INFO - mozversion platform_version: 50.0a1 16:11:15 INFO - using testdate: 1467069075 16:11:15 INFO - actual date: 1467069075 16:11:15 INFO - starting webserver on 'localhost:36029' 16:11:15 INFO - SUITE-START | Running 2 tests 16:11:15 INFO - TEST-START | dromaeo_css 16:11:15 INFO - operating with platform_type : linux_ 16:11:15 INFO - Initialising browser for dromaeo_css test... 16:11:15 INFO - TEST-INFO | started process 9795 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR1pthC/profile http://localhost:36029/getInfo.html) 16:11:19 INFO - PROCESS | 9795 | __metrics Screen width/height:1600/1200 16:11:19 INFO - PROCESS | 9795 | colorDepth:24 16:11:19 INFO - PROCESS | 9795 | Browser inner width/height: 1024/697 16:11:19 INFO - PROCESS | 9795 | __metrics 16:11:20 INFO - PROCESS | 9795 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:20 INFO - PROCESS | 9795 | console.error: PushService: 16:11:20 INFO - PROCESS | 9795 | stateChangeProcessEnqueue: Error transitioning state 16:11:20 INFO - PROCESS | 9795 | UnknownError 16:11:21 INFO - TEST-INFO | 9795: exit 0 16:11:21 INFO - Browser initialized. 16:11:21 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:21 INFO - TEST-INFO | started process 9876 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpR1pthC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:33 INFO - PROCESS | 9876 | RSS: Main: 174137344 16:11:33 INFO - PROCESS | 9876 | 16:12:24 INFO - PROCESS | 9876 | 1467069144295 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:14:33 INFO - PROCESS | 9876 | Cycle 1(1): loaded http://localhost:36029/tests/dromaeo/cssquery-dojo.html (next: http://localhost:36029/tests/dromaeo/cssquery-ext.html) 16:14:34 INFO - PROCESS | 9876 | RSS: Main: 307322880 16:14:34 INFO - PROCESS | 9876 | 16:17:37 INFO - PROCESS | 9876 | Cycle 1(1): loaded http://localhost:36029/tests/dromaeo/cssquery-ext.html (next: http://localhost:36029/tests/dromaeo/cssquery-jquery.html) 16:17:38 INFO - PROCESS | 9876 | RSS: Main: 256073728 16:17:38 INFO - PROCESS | 9876 | 16:20:39 INFO - PROCESS | 9876 | Cycle 1(1): loaded http://localhost:36029/tests/dromaeo/cssquery-jquery.html (next: http://localhost:36029/tests/dromaeo/cssquery-mootools.html) 16:20:40 INFO - PROCESS | 9876 | RSS: Main: 267776000 16:20:40 INFO - PROCESS | 9876 | 16:23:42 INFO - PROCESS | 9876 | Cycle 1(1): loaded http://localhost:36029/tests/dromaeo/cssquery-mootools.html (next: http://localhost:36029/tests/dromaeo/cssquery-prototype.html) 16:23:43 INFO - PROCESS | 9876 | RSS: Main: 261750784 16:23:43 INFO - PROCESS | 9876 | 16:26:45 INFO - PROCESS | 9876 | Cycle 1(1): loaded http://localhost:36029/tests/dromaeo/cssquery-prototype.html (next: http://localhost:36029/tests/dromaeo/cssquery-yui.html) 16:26:46 INFO - PROCESS | 9876 | RSS: Main: 227692544 16:26:46 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | Cycle 1(1): loaded http://localhost:36029/tests/dromaeo/cssquery-yui.html (next: http://localhost:36029/tests/dromaeo/cssquery-dojo.html) 16:29:49 INFO - PROCESS | 9876 | RSS: Main: 329146368 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | __start_tp_report 16:29:49 INFO - PROCESS | 9876 | _x_x_mozilla_page_load 16:29:49 INFO - PROCESS | 9876 | _x_x_mozilla_page_load_details 16:29:49 INFO - PROCESS | 9876 | |i|pagename|runs| 16:29:49 INFO - PROCESS | 9876 | |0;dojo.html;3088.583041289426;3106.285554322506;3114.174743692921;3133.9216519586994;3164.794288371256;5292.047431462345;5349.919751203735;5375.462453754622;5447.373552041511;5474.917876231854;5316.122839731449;5320.813771517997;5331.813386531472;5395.568354531638;5448.210009548613;4785.832495862637;4856.125897338477;4870.391201099863;4872.537108974641;5012.573931215851;4931.161702510582;4934.383202099738;4987.725675087869;5002.924956125661;5016.749162541876;4665.113628410591;4723.126221649006;4760.738159401231;4820.855374338776;4876.853694389174;4845.055214233226;4854.708717476963;4855.805767769285;4882.365292511986;4974.278729584226;4868.634852386085;4882.560569548722;4903.754812259373;4914.582260507861;4944.98627781304;5238.030964271584;5304.893902121975;5365.973170134124;5396.676199428047;5409.43200963901;5834.124881267801;5834.183214349994;5875.529957603381;5886.470217680429;5893.44012318829;5850.765969361263;5892.116182572606;5892.263467066617;5893.911591326134;5910.231669882888;5259.15853463444;5391.406945236021;5404.5946554008215;5410.431904649955;5477.370102438271;5920.289565252111;5928.555358348055;5943.881122377528;5945.10823376497;5948.791792287336;5736.569757268222;5737.741801618937;5738.110592858114;5743.942560574341;5747.166660834156;5554.833354999357;5608.831735047955;5714.085746280575;5714.085746280658;5741.483266506035;5730;5730.627509211888;5731.02572562657;5732.111522713986;5740.598158128891;5585.413531579125;5648.85877853057;5697.686627235542;5746.856328591819;5756.740946657411;4347.847825326098;4352.107817897323;4372.475302963601;4376.124775045004;4434.135343607965;3909.843606255718;3911.0573826534624;3914.197589494147;3946.2699400610977;3953.9604603953594;5393.784248630011;5407.351117865881;5409.864753381197;5414.3232095988005;5416.593755468356;5584.748686309126;5717.142428635738;5759;5759.337676167211;5773.538116950634;5401;5401.648892821954;5401.864953376197;5402.675839449567;5419.647722897999;4832.44426890905;4991.201407774739;5002.949970500321;5008;5051;5859.2090067841145;5898.528117750569;5906.645601263938;5948.95893218685;5949.3753155918;5753.280839895013;5757.65454072757;5761.251037365116;5763.769449221984;5767.192593036894;5842.883142337045;5937.851553711192;5939.138824870283;5946.791862284972;5959.28488581373;5685.090385538294;5737.971310143423;5742.626729262584;5747.166660834239;5800.39095894925;5776.826695199151;5781.21953536284;5808.796692115756;5817.709114544171;5834.562407819515;5492.588055895903;5604.103343465026;5615.904898544744;5690.430956904276;5694.715264236855;5642.351129620146;5646.887062258814;5651.519620832275;5659.801906933238;5688.317401911631;5524.33707955048;5537.473939975695;5545.473180047889;5555.527780138572;5560.110382338807;5461.890762184814;5500.092484739973;5651.237082993744;5666.206731057738;5731.197632331394;5409.107497262908;5418.647787893774;5424.918626220689;5431.429699881455;5475.808346707846 16:29:49 INFO - PROCESS | 9876 | |1;ext.html;579.3569138256537;609.3997412548639;614.2966303582401;640.2988727343558;641.1472741254131;31241.375172496537;42996;44006.339904901455;44035.77982110085;44102.779486102525;45278.54721452785;45465.7726711366;45775;45872.541274587245;45985;8047.155048719888;8854.91145088549;9070.092990700943;10839.078678312313;11147.498362573662;10532.42071686057;10912.181586381013;10916.07213386863;10956.12351011917;10962.177836662242;10915.181361397927;10964.506597203144;10966.232363734542;10980.505877235544;10983.560657573687;10816.513256903458;10887;10905.890941090567;10912.181586381013;10913.454327283604;10867.130629549618;10930.781384372269;10944.671659850217;10959.397233152173;10989.505472253768;43826;44195.77902110501;44234.77882610566;44318.33522497133;44381.33427998583;3493.2314890724;3626.202235508184;3672.559292884844;3689.6494832991093;3743.1765011697385;32995.6700432995;33318.6668133318;33520;34532.44603992826;34758.82620586906;4431.512533621299;4455.287154055336;4483.843065492692;4496.392986946753;4496.460424749019;341633;341661;343316;344054.27972860227;344867;63848.68075659592;64153.35846641567;64211.35788642147;64289;64349;67010.32989670138;67080.66459667769;67091.32908670949;67147;67186.64846730439;17083.231254593575;17809.376671816684;17824.91087544554;17832.732509012374;17835.46493605194;13666.863331366758;13730.176189428666;13736.244506552035;13739.381727822272;13749.587512374645;19296.903515482612;20198.899005504878;20496.18015279372;20633.277835275694;20666.689999650018;7261.346478816932;7495.775126746206;7612.505187162817;7663.808404789925;7697.923020769833;913.4017218721748;1014.8934361891895;1021.6424251511911;1102.7463683352873;1133.2350663302402;32384.190395239835;32473.188170295933;32607.021789346356;32908.67091329104;33029.50455743166;883.5689019566877;1079.1205167788228;1098.6429410441638;1137.6814491942268;1176.8352430659716;16800.15999200035;16997.575060623585;17102.572435689206;17145.59865665815;17385.04382258988;532821;533498;533743;537483;537714;59225;59840.40159598435;59850.40149598535;59900.40099598948;59927.40072599305;1212.2847519963263;1338.9263590502617;1354.0724603646534;1362.2439546052037;1372.0807059270312;32156.517652235703;33382.66617333796;33441.33117337591;33860;34406.82796586001;49148.508514915826;49769.25346119884;49820.50179498159;49915;49941.500584993686;417592.208350631;417618;418488;419475;420686;33555.49666755049;43460.130797383244;44327.556724433634;46968.530314696414;48001;47837;48038.27942580934;48185.27722084102;48947.755261223465;50605.24092138547;40062;42342.57657423386;42345;42721.78639106784;42782.78608607061;40457.39313910352;40814.79592602137;41008.17983640251;41629.375559366024;41646.37530437107;42243;42753.5724642762;43048.354274685276;43292.56707432885;43329.35005974976;34807.30385392329;36977.26045479129;37008.38104555885;37755.43366849554;37893.24213515659;26870.32824179411;27155.45689086168;27159.86420067887;27221.86389068042;27464.313392165357 16:29:49 INFO - PROCESS | 9876 | |2;jquery.html;370.86092715231797;374.4103037715598;384.5462354421781;387.9398693202553;400.6754528831645;18179.545511362205;18913.24347026117;19121.139548720304;19671.50821229468;19675.606487870235;18505.814941850578;18535.351262705808;18832.34086806962;18832.811671883275;18837.62324753504;5432.67403955762;5442.156465747816;5639.718014099279;5668.093105103184;5668.546516278709;5609.242752228438;5691.857703557403;5718.313802343725;5728.312602487707;5732.856678583048;5491.807786727465;5659.179418984245;5699.772009119631;5704.372519022904;5706.3722990471015;5744.454276843693;5753.942460575383;5778.508826749731;5806.65160090396;5807.070868661015;5518.944810551923;5541.347395289709;5611.775528978836;5663.7168141593165;5680.31836179657;21652.675209871864;22212.222572209896;22365.10539578415;22429.55140897173;22830.77169228303;2237.4182712494708;2286.7255929288426;2315.9189428369928;2575.22743177048;2596.675415573053;9409.153176214173;9677.612895484172;9953.25350598708;10291.125254353388;10522.579096836118;1916.7604049493814;1942.80571942806;1994.044665012407;2019.989900050505;2030.0661695620033;84739;85071.57464212639;85565;86771.56614216951;88483.55758221232;10741.731456713645;10845.620403285848;10861.891381086247;10883.945580272206;11046.447677616086;14764;14969.101853888802;15014.324355404033;15019.924900375428;15022.474213402478;7726.266004729542;7859.4891332063235;7965.6017199139815;8001.079875814239;8168.509889406655;7503.7748867534065;7540.660670269741;7657.272559106876;7755.961220193863;7784.649690763929;4536.9092618148115;4625.4911959684405;4654.301854721751;4670.416197975253;4808.158572249842;1854.3509771579838;1892.5174080609631;1918.5779128591687;1920.8943508107188;1978.8317992970472;880.6873559886276;928.3455164109287;978.6574698855344;1005.9346142500714;1021.1371391174446;18520.536986575444;18824.529386765167;19685;19715.704264435757;20243.696344554843;1006.3710181136789;1006.6476733143488;1006.6979906028163;1013.0882206014646;1045.5869931377063;9173.81652366949;10666.679999600025;10884.72788180286;10916.181286403551;11030.06244469229;357433;359949;363816;448580;457207;91661.54169229245;94810.52594736982;102284.48857755664;102946.48526757318;103977;1015.5836107196162;1051.4112097225577;1053.847192157133;1064.302881612545;1074.1889873145724;17306.653866922843;17564.73652895182;17818.10909454496;17874.106294684952;18092.095395229924;41584.376234357114;42525.57474425216;42907.570924290354;43257.56742432661;44129.92505861867;59559.404405955385;59928.70035649794;60394.698026509584;61508.384916150266;63233;7184.425245980414;7281.708731650675;7527.209642987407;7569.5079819811535;7614.124375696866;7751.263629955145;10563.207759418228;12030.398480076135;13217.933910330386;13604.25176615256;11946.104042196695;11955.342456164994;12030.27818330903;12142.210756300812;12219.26684398939;5966.850828729316;10870.45647717595;11410.372429516456;11450.656480305604;12090.093243006633;10679.946600266949;10994.175436842108;11850.762984740084;12125.713431198923;12314.445849936954;7597.582132982518;7598.468107232441;7620.3522700571075;7695.4997925134685;7852.097008843828;10042.648507302209;11969.102317326062;11999.880001199876;12080.335581542748;12162.635120946386 16:29:49 INFO - PROCESS | 9876 | |3;mootools.html;1234.8888600025996;1469.9485518006868;1487.048289094979;1622.302409963716;1759.7888253409592;7700.845983080342;8042.879356809653;8044.5575493347915;8335.249827515523;8352.749417517483;8001.839963200732;8260.545669988149;8262.256396924276;8263.83472330553;8266.256036956673;1957.1290775604791;2212.893553223388;2252.1892118837245;2282.7032485776826;2293.3234695764704;2292.805111565519;2297.3682237384687;2298.5977453945493;2300.298408985259;2303.9654405183937;2226.387743370578;2282.2354511238755;2283;2283.600369935263;2289.290320000805;1931.449536881989;2188.529466164782;2277.1119263487253;2280.6579013147993;2296.781805728453;2214.3910424633195;2234.6648002799548;2239.585676649825;2240.0703707961175;2242.742084660252;8374;8400.495970241744;8438.240558349778;8439.915600843975;8449.112843151443;4866.878328041792;4993.800247990076;4996.800127994876;4996.875078123039;5003.649744517848;3227.451333273326;3313.950290745641;3323.401787678217;3357.4963755436634;3370.4775759757276;1011.1102230037593;1049.6588608702125;1059.3220338983058;1062.1534288107869;2076.200662744839;11390.943045284721;11515.30908145514;11521.251118677259;11533.711657208552;11545.942270288679;4080.571539988318;4233.216854881856;4294.162638285567;4295.52749197588;4316.913661726748;9150.130737579919;9222.446653200695;9225.169734723908;9225.354225204306;9226.077392260855;4437.733735975852;4931.0630980113665;4946.579540739005;4947;4950.8267210647455;4204.474440694913;4259.637930775919;4276.273033584298;4323.63249123828;4487.057717879282;3625.528681271418;3644.908877278036;3701.833417496219;3755.849766009329;3758.229562939591;1087.2497976396262;1142.731458107344;1146.5929594993656;1147.145376694368;1157.791597512439;904.1817155474306;909.6977969969103;1002.4987506246877;1032.3702541449709;1034.2139973620103;5770.42295770428;5848.561357898089;5854.443827836349;5946.524278057745;5966.164736936832;960.86547883295;961.6874515782399;1003.8494225866178;1014.0619926568043;1015.8374660054355;4063.3904914262507;4156.501219853637;4240.660747140221;4255.489341279066;4264.744115353026;10095.899041009643;10310.432926188982;10454.581816727397;10477.161827053817;10478.37129772216;9674;10230.488475576192;10376.636817711495;10381.429021403896;10396.48017599132;7523.510971786816;7811.570363629941;7919;7919.960400198077;7932.484388514844;5692.857678558069;5840.649561026182;5974.790882319098;6084.482818960438;6122.326544080237;9404.811903761749;9463.242940564876;9517.619295228113;9531.428114313163;9538.046195380406;9257.86113208289;9634.325597208128;9718.140231058676;9813.460259685786;9852.950735246564;6941.444684425132;7421.18366979621;7616;7628.80927976805;7661.846763064596;8344.833103337778;8349.83300334002;8398.328133749408;8406.411551191359;8436.662533498591;7258.0927384076995;7305.926940730525;7445.292697193758;7569.924300756922;7614.086309642878;7250.891236631349;7300.12398512182;7388.593627350548;7435.107787065586;7526.548407095592;6661.866762664623;6662.83342916431;6726.764563240263;6729.596224226562;6732.629705366252;4904.754762261944;5075.7969681212335;5091.13450713387;5120.385553733576;5149.639525233198;4982.4768399319;5127.051495473349;5134.255532947776;5227.660202086852;5285.445028271976 16:29:49 INFO - PROCESS | 9876 | |4;prototype.html;312.62328893683116;315.3252040633045;323.50019220304637;329.3166679140783;330.17127011201893;5177.326947496829;5281.86795330116;5348.609361565992;5392.811251606195;5405.972970135153;5290.68255904646;5308.5487733542595;5320.334958130234;5336.706481143545;5354.330708661418;4198.9370159447635;4201.655470249514;4203.85286514972;4204.939579605986;4209.936850947238;3945.7435266707716;3981.422693709425;4167.604077612637;4227.196832601796;4235.555266696999;4104.363823607346;4140.958590414088;4142.565030671782;4162.916741665165;4175.6033176848305;4102.200070986159;4122.196171746511;4138.213739389536;4138.592878421351;4148.709590328678;3473.947890781615;3681.1349332906743;3700.481932529448;3702.611225821304;3705.203381273039;5176.327077479941;5187.0922588546855;5196.506331898464;5220.817271395483;5231.790728370861;4739.905201895977;4740.336352910595;4777.402824646919;4783.449903261135;4785.976070119662;5443.945560544423;5537.612367134302;5565.332160140769;5565.833025009256;5586;3941.507311586052;3950.3876899080688;3952.545457272393;3955.367141257414;3970.8014599269923;5767.452092051207;5789.16057171712;5797.681127537984;5815.970920145414;5828.475437210629;3860.247251785931;4042.4744783178;4077.959220407758;4078.46979892618;4088.039310762028;4073.6944729145434;4079.8164082616354;4084.7753373564738;4086.1827634473225;4091.9590804091577;4660.440747110369;4665.720056796603;4678.368420263289;4688.906221875543;4698.154332220233;5415.431379705153;5520.861978450571;5554.389017208104;5554.583406244547;5559.471850174227;1410.3441899516774;1435.7272118297699;1436.727021865842;1439.4242303078852;1440.9985060383142;891.9108089191029;901.9594118264694;921.7603423109906;924.4453328003278;928.6053427293373;4742.932840110948;4766.547178018082;4849.806007759721;4875.341828853059;4886.118844244991;5035.590034790264;5063.822766203239;5128.692278463304;5131.204663277198;5144.022635699205;4518.6728834650185;4835.8065677373215;4860.517542149678;4867.221244600847;4906.681065730716;2715.769159621415;2731.7814574833965;2738.342797728531;2739.7534121974454;2756.572731226663;5212.244224587489;5233.278695078538;5246.23929530216;5256.39551451588;5280.155175172031;4821.807127714922;5185.118529849935;5195.246689230041;5221.712805795718;5222.634415590872;5166.483351664879;5180.119379705459;5205.323307970016;5213.478652134756;5255.214736189825;5050.3434553507805;5058.822941197041;5078.441371449211;5095.77069031887;5154.304168937296;4833.419989601271;4928.704277743347;5003.574696150722;5041.159976608585;5066.392032956069;5199.29809475716;5245.295279034252;5248.475152484721;5249.895002100013;5257.842264731911;3819.942700859545;3848.615138486129;4083.775392353449;4140.503139623264;4243.087736136746;5722.856928576819;5724.570657200643;5758.1362795581;5761.8559536011935;5793.478586927196;5467.507924286833;5607.327120745374;5610.719464026701;5617.438256174513;5622.437756224344;5605.439456054362;5613.989481893298;5615.213870058276;5643.576731745054;5666.186820217491;5526.088195447867;5542.806001789918;5555.2778138841695;5555.555555555627;5561.277033985556;5425.186222066722;5644.322681278273;5647.830565083054;5670.858228544319;5685;5451.37309209446;5480.3971563127225;5493.395726470165;5497.642653227527;5506.146547285177 16:29:49 INFO - PROCESS | 9876 | |5;yui.html;759.7858335108149;763.0690557519824;776.2315307845233;786.6263524825708;789.8696715042018;14383.640408989768;14659;14898.702025959488;15851.034620472745;16671.333146674147;15799.131047792367;15976.600584985352;15994.520164395057;16004.199790010482;16018.83981160188;801.4029547986758;804.1797366685977;808.5027707959592;808.6320724070563;809.1180613131685;667.8864593019174;684.0252639988022;791.9881201781977;810.1817164663673;817.1379194949326;832.5421018439864;832.9333653307721;836.3518273338182;836.8368168207202;837.1962915601;833.5248908122372;834.6202477872583;835.1231207232381;835.5237514616671;835.7534527314457;510.7344181025846;525.1073175601455;593.2554643921893;611.3275397063239;616.7995401494533;14485.927570362186;14641.19473428961;14712.779308310384;14740.336684849208;14808.851911480855;12933.224006559636;13512.662183445394;13530.323483825769;13549.86450135496;13792.207792207691;12055.276683399024;12340.321282329469;12341.233924468583;12457.128001039931;12894.097413181082;4431;4480;4540.614047805908;4673.906521869578;4687.484376718592;74513;74722.62638686826;74958.62520687416;77465;77546.99999999943;10157.847632285522;10326.432046237529;10441.269111162146;10600.836986847287;10662.520186591622;18132.546686332946;18146.63706725858;18150.909245453688;18151.455456336065;18171.63656726858;8139.633716482773;8151.83696326082;8216.630251638691;8221.054578723404;8422.957885210657;4481.417415735934;4647.767611619405;4704.764761761967;4770.72214355733;4783.832565860178;3311.817850018224;3457.0493114393744;3468.9826550867588;3470.4447288434235;3473.704735097545;642.2870613618879;651.221789960992;659.4856012310405;661.4014317042987;663.5454713521157;628.0829988217163;704.2957042957042;765.9846803064019;787.0948409329203;820.1429506166071;9577.712668619952;9685.25902378899;9749.463779492196;9826.164775993977;9856.05134020326;625.4589779840496;705.9047028651066;737.1964558631117;759.1649185895582;772.212343409719;4394.934075988863;4844.515548445198;4883.902321953541;4885.8289959851945;4899.216125419915;110893.44553277343;112664;112947;113473.43263283631;114639.42680286712;97891.51054244826;98010;99873;101024;101156;55540.444595554334;86685;88758.55620721984;88810.11189888019;89226.10773892308;8781.604827782892;9217.354785164975;9362.90354762773;9421.717348479557;9449.149576538144;52321.73839130932;52585.7370713144;52718.04614653839;53946.730266348415;54226.45773542214;63720.362796371446;72550.63724681342;73924.84187027162;77010.6149469249;79236.60381698054;13092.214467132002;13239.536616218385;13618.114822536503;13654.249016304198;13709.931450342685;15050.473233436776;15215.923920380328;15306.846931530989;15384.307706153026;15685.843141568439;12763.298018608693;13162.868371316546;14476.710465790837;14612.926935365254;15122.924385378003;13282.933585332397;13327.466901323838;13645.454181833013;14031.719365612427;15187.696246075238;13639.386227619583;13685;13840.051030578785;14165.362558684677;15020.699586008437;10704.839427408438;10909.39998300101;10917.563297468012;11300.604478843201;11319.547218111184;10931.344119352865;11472.483738231633;11897;11921.105917056082;11933.940330298641 16:29:49 INFO - PROCESS | 9876 | __end_tp_report 16:29:49 INFO - PROCESS | 9876 | __start_cc_report 16:29:49 INFO - PROCESS | 9876 | _x_x_mozilla_cycle_collect,1121 16:29:49 INFO - PROCESS | 9876 | __end_cc_report 16:29:49 INFO - PROCESS | 9876 | __startTimestamp1467070189721__endTimestamp 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | ------- Summary: start ------- 16:29:49 INFO - PROCESS | 9876 | Number of tests: 6 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | [#0] dojo.html Cycles:165 Average:5343.75 Median:5500.09 stddev:611.87 (11.1%) stddev-sans-first:587.61 16:29:49 INFO - PROCESS | 9876 | Values: 3088.6 3106.3 3114.2 3133.9 3164.8 5292.0 5349.9 5375.5 5447.4 5474.9 5316.1 5320.8 5331.8 5395.6 5448.2 4785.8 4856.1 4870.4 4872.5 5012.6 4931.2 4934.4 4987.7 5002.9 5016.7 4665.1 4723.1 4760.7 4820.9 4876.9 4845.1 4854.7 4855.8 4882.4 4974.3 4868.6 4882.6 4903.8 4914.6 4945.0 5238.0 5304.9 5366.0 5396.7 5409.4 5834.1 5834.2 5875.5 5886.5 5893.4 5850.8 5892.1 5892.3 5893.9 5910.2 5259.2 5391.4 5404.6 5410.4 5477.4 5920.3 5928.6 5943.9 5945.1 5948.8 5736.6 5737.7 5738.1 5743.9 5747.2 5554.8 5608.8 5714.1 5714.1 5741.5 5730.0 5730.6 5731.0 5732.1 5740.6 5585.4 5648.9 5697.7 5746.9 5756.7 4347.8 4352.1 4372.5 4376.1 4434.1 3909.8 3911.1 3914.2 3946.3 3954.0 5393.8 5407.4 5409.9 5414.3 5416.6 5584.7 5717.1 5759.0 5759.3 5773.5 5401.0 5401.6 5401.9 5402.7 5419.6 4832.4 4991.2 5002.9 5008.0 5051.0 5859.2 5898.5 5906.6 5949.0 5949.4 5753.3 5757.7 5761.3 5763.8 5767.2 5842.9 5937.9 5939.1 5946.8 5959.3 5685.1 5738.0 5742.6 5747.2 5800.4 5776.8 5781.2 5808.8 5817.7 5834.6 5492.6 5604.1 5615.9 5690.4 5694.7 5642.4 5646.9 5651.5 5659.8 5688.3 5524.3 5537.5 5545.5 5555.5 5560.1 5461.9 5500.1 5651.2 5666.2 5731.2 5409.1 5418.6 5424.9 5431.4 5475.8 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | [#1] ext.html Cycles:180 Average:60905.96 Median:37824.34 stddev:116170.72 (307.1%) stddev-sans-first:116408.31 16:29:49 INFO - PROCESS | 9876 | Values: 579.4 609.4 614.3 640.3 641.1 31241.4 42996.0 44006.3 44035.8 44102.8 45278.5 45465.8 45775.0 45872.5 45985.0 8047.2 8854.9 9070.1 10839.1 11147.5 10532.4 10912.2 10916.1 10956.1 10962.2 10915.2 10964.5 10966.2 10980.5 10983.6 10816.5 10887.0 10905.9 10912.2 10913.5 10867.1 10930.8 10944.7 10959.4 10989.5 43826.0 44195.8 44234.8 44318.3 44381.3 3493.2 3626.2 3672.6 3689.6 3743.2 32995.7 33318.7 33520.0 34532.4 34758.8 4431.5 4455.3 4483.8 4496.4 4496.5 341633.0 341661.0 343316.0 344054.3 344867.0 63848.7 64153.4 64211.4 64289.0 64349.0 67010.3 67080.7 67091.3 67147.0 67186.6 17083.2 17809.4 17824.9 17832.7 17835.5 13666.9 13730.2 13736.2 13739.4 13749.6 19296.9 20198.9 20496.2 20633.3 20666.7 7261.3 7495.8 7612.5 7663.8 7697.9 913.4 1014.9 1021.6 1102.7 1133.2 32384.2 32473.2 32607.0 32908.7 33029.5 883.6 1079.1 1098.6 1137.7 1176.8 16800.2 16997.6 17102.6 17145.6 17385.0 532821.0 533498.0 533743.0 537483.0 537714.0 59225.0 59840.4 59850.4 59900.4 59927.4 1212.3 1338.9 1354.1 1362.2 1372.1 32156.5 33382.7 33441.3 33860.0 34406.8 49148.5 49769.3 49820.5 49915.0 49941.5 417592.2 417618.0 418488.0 419475.0 420686.0 33555.5 43460.1 44327.6 46968.5 48001.0 47837.0 48038.3 48185.3 48947.8 50605.2 40062.0 42342.6 42345.0 42721.8 42782.8 40457.4 40814.8 41008.2 41629.4 41646.4 42243.0 42753.6 43048.4 43292.6 43329.4 34807.3 36977.3 37008.4 37755.4 37893.2 26870.3 27155.5 27159.9 27221.9 27464.3 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | [#2] jquery.html Cycles:180 Average:26681.00 Median:2301.32 stddev:67007.56 (2911.7%) stddev-sans-first:67166.41 16:29:49 INFO - PROCESS | 9876 | Values: 370.9 374.4 384.5 387.9 400.7 18179.5 18913.2 19121.1 19671.5 19675.6 18505.8 18535.4 18832.3 18832.8 18837.6 5432.7 5442.2 5639.7 5668.1 5668.5 5609.2 5691.9 5718.3 5728.3 5732.9 5491.8 5659.2 5699.8 5704.4 5706.4 5744.5 5753.9 5778.5 5806.7 5807.1 5518.9 5541.3 5611.8 5663.7 5680.3 21652.7 22212.2 22365.1 22429.6 22830.8 2237.4 2286.7 2315.9 2575.2 2596.7 9409.2 9677.6 9953.3 10291.1 10522.6 1916.8 1942.8 1994.0 2020.0 2030.1 84739.0 85071.6 85565.0 86771.6 88483.6 10741.7 10845.6 10861.9 10883.9 11046.4 14764.0 14969.1 15014.3 15019.9 15022.5 7726.3 7859.5 7965.6 8001.1 8168.5 7503.8 7540.7 7657.3 7756.0 7784.6 4536.9 4625.5 4654.3 4670.4 4808.2 1854.4 1892.5 1918.6 1920.9 1978.8 880.7 928.3 978.7 1005.9 1021.1 18520.5 18824.5 19685.0 19715.7 20243.7 1006.4 1006.6 1006.7 1013.1 1045.6 9173.8 10666.7 10884.7 10916.2 11030.1 357433.0 359949.0 363816.0 448580.0 457207.0 91661.5 94810.5 102284.5 102946.5 103977.0 1015.6 1051.4 1053.8 1064.3 1074.2 17306.7 17564.7 17818.1 17874.1 18092.1 41584.4 42525.6 42907.6 43257.6 44129.9 59559.4 59928.7 60394.7 61508.4 63233.0 7184.4 7281.7 7527.2 7569.5 7614.1 7751.3 10563.2 12030.4 13217.9 13604.3 11946.1 11955.3 12030.3 12142.2 12219.3 5966.9 10870.5 11410.4 11450.7 12090.1 10679.9 10994.2 11850.8 12125.7 12314.4 7597.6 7598.5 7620.4 7695.5 7852.1 10042.6 11969.1 11999.9 12080.3 12162.6 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | [#3] mootools.html Cycles:180 Average:5528.74 Median:4948.91 stddev:3078.88 (62.2%) stddev-sans-first:3070.60 16:29:49 INFO - PROCESS | 9876 | Values: 1234.9 1469.9 1487.0 1622.3 1759.8 7700.8 8042.9 8044.6 8335.2 8352.7 8001.8 8260.5 8262.3 8263.8 8266.3 1957.1 2212.9 2252.2 2282.7 2293.3 2292.8 2297.4 2298.6 2300.3 2304.0 2226.4 2282.2 2283.0 2283.6 2289.3 1931.4 2188.5 2277.1 2280.7 2296.8 2214.4 2234.7 2239.6 2240.1 2242.7 8374.0 8400.5 8438.2 8439.9 8449.1 4866.9 4993.8 4996.8 4996.9 5003.6 3227.5 3314.0 3323.4 3357.5 3370.5 1011.1 1049.7 1059.3 1062.2 2076.2 11390.9 11515.3 11521.3 11533.7 11545.9 4080.6 4233.2 4294.2 4295.5 4316.9 9150.1 9222.4 9225.2 9225.4 9226.1 4437.7 4931.1 4946.6 4947.0 4950.8 4204.5 4259.6 4276.3 4323.6 4487.1 3625.5 3644.9 3701.8 3755.8 3758.2 1087.2 1142.7 1146.6 1147.1 1157.8 904.2 909.7 1002.5 1032.4 1034.2 5770.4 5848.6 5854.4 5946.5 5966.2 960.9 961.7 1003.8 1014.1 1015.8 4063.4 4156.5 4240.7 4255.5 4264.7 10095.9 10310.4 10454.6 10477.2 10478.4 9674.0 10230.5 10376.6 10381.4 10396.5 7523.5 7811.6 7919.0 7920.0 7932.5 5692.9 5840.6 5974.8 6084.5 6122.3 9404.8 9463.2 9517.6 9531.4 9538.0 9257.9 9634.3 9718.1 9813.5 9853.0 6941.4 7421.2 7616.0 7628.8 7661.8 8344.8 8349.8 8398.3 8406.4 8436.7 7258.1 7305.9 7445.3 7569.9 7614.1 7250.9 7300.1 7388.6 7435.1 7526.5 6661.9 6662.8 6726.8 6729.6 6732.6 4904.8 5075.8 5091.1 5120.4 5149.6 4982.5 5127.1 5134.3 5227.7 5285.4 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | [#4] prototype.html Cycles:180 Average:4530.87 Median:5087.11 stddev:1308.51 (25.7%) stddev-sans-first:1273.30 16:29:49 INFO - PROCESS | 9876 | Values: 312.6 315.3 323.5 329.3 330.2 5177.3 5281.9 5348.6 5392.8 5406.0 5290.7 5308.5 5320.3 5336.7 5354.3 4198.9 4201.7 4203.9 4204.9 4209.9 3945.7 3981.4 4167.6 4227.2 4235.6 4104.4 4141.0 4142.6 4162.9 4175.6 4102.2 4122.2 4138.2 4138.6 4148.7 3473.9 3681.1 3700.5 3702.6 3705.2 5176.3 5187.1 5196.5 5220.8 5231.8 4739.9 4740.3 4777.4 4783.4 4786.0 5443.9 5537.6 5565.3 5565.8 5586.0 3941.5 3950.4 3952.5 3955.4 3970.8 5767.5 5789.2 5797.7 5816.0 5828.5 3860.2 4042.5 4078.0 4078.5 4088.0 4073.7 4079.8 4084.8 4086.2 4092.0 4660.4 4665.7 4678.4 4688.9 4698.2 5415.4 5520.9 5554.4 5554.6 5559.5 1410.3 1435.7 1436.7 1439.4 1441.0 891.9 902.0 921.8 924.4 928.6 4742.9 4766.5 4849.8 4875.3 4886.1 5035.6 5063.8 5128.7 5131.2 5144.0 4518.7 4835.8 4860.5 4867.2 4906.7 2715.8 2731.8 2738.3 2739.8 2756.6 5212.2 5233.3 5246.2 5256.4 5280.2 4821.8 5185.1 5195.2 5221.7 5222.6 5166.5 5180.1 5205.3 5213.5 5255.2 5050.3 5058.8 5078.4 5095.8 5154.3 4833.4 4928.7 5003.6 5041.2 5066.4 5199.3 5245.3 5248.5 5249.9 5257.8 3819.9 3848.6 4083.8 4140.5 4243.1 5722.9 5724.6 5758.1 5761.9 5793.5 5467.5 5607.3 5610.7 5617.4 5622.4 5605.4 5614.0 5615.2 5643.6 5666.2 5526.1 5542.8 5555.3 5555.6 5561.3 5425.2 5644.3 5647.8 5670.9 5685.0 5451.4 5480.4 5493.4 5497.6 5506.1 16:29:49 INFO - PROCESS | 9876 | 16:29:49 INFO - PROCESS | 9876 | [#5] yui.html Cycles:180 Average:20606.14 Median:4737.74 stddev:29570.85 (624.2%) stddev-sans-first:29616.25 16:29:49 INFO - PROCESS | 9876 | Values: 759.8 763.1 776.2 786.6 789.9 14383.6 14659.0 14898.7 15851.0 16671.3 15799.1 15976.6 15994.5 16004.2 16018.8 801.4 804.2 808.5 808.6 809.1 667.9 684.0 792.0 810.2 817.1 832.5 832.9 836.4 836.8 837.2 833.5 834.6 835.1 835.5 835.8 510.7 525.1 593.3 611.3 616.8 14485.9 14641.2 14712.8 14740.3 14808.9 12933.2 13512.7 13530.3 13549.9 13792.2 12055.3 12340.3 12341.2 12457.1 12894.1 4431.0 4480.0 4540.6 4673.9 4687.5 74513.0 74722.6 74958.6 77465.0 77547.0 10157.8 10326.4 10441.3 10600.8 10662.5 18132.5 18146.6 18150.9 18151.5 18171.6 8139.6 8151.8 8216.6 8221.1 8423.0 4481.4 4647.8 4704.8 4770.7 4783.8 3311.8 3457.0 3469.0 3470.4 3473.7 642.3 651.2 659.5 661.4 663.5 628.1 704.3 766.0 787.1 820.1 9577.7 9685.3 9749.5 9826.2 9856.1 625.5 705.9 737.2 759.2 772.2 4394.9 4844.5 4883.9 4885.8 4899.2 110893.4 112664.0 112947.0 113473.4 114639.4 97891.5 98010.0 99873.0 101024.0 101156.0 55540.4 86685.0 88758.6 88810.1 89226.1 8781.6 9217.4 9362.9 9421.7 9449.1 52321.7 52585.7 52718.0 53946.7 54226.5 63720.4 72550.6 73924.8 77010.6 79236.6 13092.2 13239.5 13618.1 13654.2 13709.9 15050.5 15215.9 15306.8 15384.3 15685.8 12763.3 13162.9 14476.7 14612.9 15122.9 13282.9 13327.5 13645.5 14031.7 15187.7 13639.4 13685.0 13840.1 14165.4 15020.7 10704.8 10909.4 10917.6 11300.6 11319.5 10931.3 11472.5 11897.0 11921.1 11933.9 16:29:49 INFO - PROCESS | 9876 | -------- Summary: end -------- 16:29:49 INFO - PROCESS | 9876 | 16:29:50 INFO - TEST-INFO | 9876: exit 0 16:29:50 INFO - TEST-OK | dromaeo_css | took 1115358ms 16:29:50 INFO - TEST-START | kraken 16:29:50 INFO - operating with platform_type : linux_ 16:29:50 INFO - Initialising browser for kraken test... 16:29:50 INFO - TEST-INFO | started process 10064 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8OsC7z/profile http://localhost:36029/getInfo.html) 16:29:54 INFO - PROCESS | 10064 | __metrics Screen width/height:1600/1200 16:29:54 INFO - PROCESS | 10064 | colorDepth:24 16:29:54 INFO - PROCESS | 10064 | Browser inner width/height: 1024/697 16:29:54 INFO - PROCESS | 10064 | __metrics 16:29:55 INFO - PROCESS | 10064 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:56 INFO - TEST-INFO | 10064: exit 0 16:29:56 INFO - Browser initialized. 16:29:56 INFO - Running cycle 1/1 for kraken test... 16:29:56 INFO - TEST-INFO | started process 10149 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp8OsC7z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:30:08 INFO - PROCESS | 10149 | RSS: Main: 173658112 16:30:08 INFO - PROCESS | 10149 | 16:30:11 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:36029/tests/kraken/driver.html?testName=audio-beat-detection) 16:30:11 INFO - PROCESS | 10149 | RSS: Main: 231936000 16:30:11 INFO - PROCESS | 10149 | 16:30:14 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:36029/tests/kraken/driver.html?testName=audio-dft) 16:30:15 INFO - PROCESS | 10149 | RSS: Main: 215072768 16:30:15 INFO - PROCESS | 10149 | 16:30:18 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:36029/tests/kraken/driver.html?testName=audio-fft) 16:30:18 INFO - PROCESS | 10149 | RSS: Main: 284905472 16:30:18 INFO - PROCESS | 10149 | 16:30:21 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:36029/tests/kraken/driver.html?testName=audio-oscillator) 16:30:21 INFO - PROCESS | 10149 | RSS: Main: 196759552 16:30:21 INFO - PROCESS | 10149 | 16:30:23 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:36029/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:24 INFO - PROCESS | 10149 | RSS: Main: 200273920 16:30:24 INFO - PROCESS | 10149 | 16:30:27 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:36029/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:28 INFO - PROCESS | 10149 | RSS: Main: 236978176 16:30:28 INFO - PROCESS | 10149 | 16:30:31 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:36029/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:31 INFO - PROCESS | 10149 | RSS: Main: 238178304 16:30:31 INFO - PROCESS | 10149 | 16:30:34 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:36029/tests/kraken/driver.html?testName=json-parse-financial) 16:30:35 INFO - PROCESS | 10149 | RSS: Main: 237174784 16:30:35 INFO - PROCESS | 10149 | 16:30:36 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:36029/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:37 INFO - PROCESS | 10149 | RSS: Main: 223838208 16:30:37 INFO - PROCESS | 10149 | 16:30:39 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:39 INFO - PROCESS | 10149 | RSS: Main: 228040704 16:30:39 INFO - PROCESS | 10149 | 16:30:41 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:42 INFO - PROCESS | 10149 | RSS: Main: 236118016 16:30:42 INFO - PROCESS | 10149 | 16:30:45 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:45 INFO - PROCESS | 10149 | RSS: Main: 236810240 16:30:45 INFO - PROCESS | 10149 | 16:30:48 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:49 INFO - PROCESS | 10149 | RSS: Main: 237105152 16:30:49 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | Cycle 1(1): loaded http://localhost:36029/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:36029/tests/kraken/driver.html?testName=ai-astar) 16:30:50 INFO - PROCESS | 10149 | RSS: Main: 279846912 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | __start_tp_report 16:30:50 INFO - PROCESS | 10149 | _x_x_mozilla_page_load 16:30:50 INFO - PROCESS | 10149 | _x_x_mozilla_page_load_details 16:30:50 INFO - PROCESS | 10149 | |i|pagename|runs| 16:30:50 INFO - PROCESS | 10149 | |0;ai-astar;104;113;110;109;111;110;109;110;110;110 16:30:50 INFO - PROCESS | 10149 | |1;audio-beat-detection;157;126;128;180;126;127;161;124;162;128 16:30:50 INFO - PROCESS | 10149 | |2;audio-dft;162;160;160;160;160;162;161;162;164;164 16:30:50 INFO - PROCESS | 10149 | |3;audio-fft;75;75;77;109;74;75;76;75;103;76 16:30:50 INFO - PROCESS | 10149 | |4;audio-oscillator;85;88;116;86;86;86;130;86;86;127 16:30:50 INFO - PROCESS | 10149 | |5;imaging-gaussian-blur;117;117;116;117;116;117;117;117;117;117 16:30:50 INFO - PROCESS | 10149 | |6;imaging-darkroom;146;146;147;147;147;147;147;145;145;145 16:30:50 INFO - PROCESS | 10149 | |7;imaging-desaturate;96;95;94;95;95;95;95;95;95;96 16:30:50 INFO - PROCESS | 10149 | |8;json-parse-financial;54;52;52;54;55;53;56;53;53;54 16:30:50 INFO - PROCESS | 10149 | |9;json-stringify-tinderbox;63;64;63;64;65;63;63;64;64;64 16:30:50 INFO - PROCESS | 10149 | |10;stanford-crypto-aes;80;80;79;79;84;79;87;81;103;80 16:30:50 INFO - PROCESS | 10149 | |11;stanford-crypto-ccm;124;130;126;119;126;119;127;123;131;124 16:30:50 INFO - PROCESS | 10149 | |12;stanford-crypto-pbkdf2;160;158;161;158;163;160;159;161;163;158 16:30:50 INFO - PROCESS | 10149 | |13;stanford-crypto-sha256-iterative;66;67;63;66;64;66;64;64;63;61 16:30:50 INFO - PROCESS | 10149 | __end_tp_report 16:30:50 INFO - PROCESS | 10149 | __start_cc_report 16:30:50 INFO - PROCESS | 10149 | _x_x_mozilla_cycle_collect,1602 16:30:50 INFO - PROCESS | 10149 | __end_cc_report 16:30:50 INFO - PROCESS | 10149 | __startTimestamp1467070250955__endTimestamp 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | ------- Summary: start ------- 16:30:50 INFO - PROCESS | 10149 | Number of tests: 14 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#0] ai-astar Cycles:10 Average:109.60 Median:110.00 stddev:2.27 (2.1%) stddev-sans-first:1.20 16:30:50 INFO - PROCESS | 10149 | Values: 104.0 113.0 110.0 109.0 111.0 110.0 109.0 110.0 110.0 110.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#1] audio-beat-detection Cycles:10 Average:141.90 Median:142.50 stddev:20.77 (14.6%) stddev-sans-first:21.30 16:30:50 INFO - PROCESS | 10149 | Values: 157.0 126.0 128.0 180.0 126.0 127.0 161.0 124.0 162.0 128.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#2] audio-dft Cycles:10 Average:161.50 Median:162.00 stddev:1.58 (1.0%) stddev-sans-first:1.67 16:30:50 INFO - PROCESS | 10149 | Values: 162.0 160.0 160.0 160.0 160.0 162.0 161.0 162.0 164.0 164.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#3] audio-fft Cycles:10 Average:81.50 Median:75.00 stddev:13.01 (17.4%) stddev-sans-first:13.59 16:30:50 INFO - PROCESS | 10149 | Values: 75.0 75.0 77.0 109.0 74.0 75.0 76.0 75.0 103.0 76.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:86.00 stddev:18.79 (21.8%) stddev-sans-first:19.36 16:30:50 INFO - PROCESS | 10149 | Values: 85.0 88.0 116.0 86.0 86.0 86.0 130.0 86.0 86.0 127.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#5] imaging-gaussian-blur Cycles:10 Average:116.80 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 16:30:50 INFO - PROCESS | 10149 | Values: 117.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:147.00 stddev:0.92 (0.6%) stddev-sans-first:0.97 16:30:50 INFO - PROCESS | 10149 | Values: 146.0 146.0 147.0 147.0 147.0 147.0 147.0 145.0 145.0 145.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 16:30:50 INFO - PROCESS | 10149 | Values: 96.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:1.26 (2.3%) stddev-sans-first:1.33 16:30:50 INFO - PROCESS | 10149 | Values: 54.0 52.0 52.0 54.0 55.0 53.0 56.0 53.0 53.0 54.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 16:30:50 INFO - PROCESS | 10149 | Values: 63.0 64.0 63.0 64.0 65.0 63.0 63.0 64.0 64.0 64.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:80.00 stddev:7.42 (9.3%) stddev-sans-first:7.78 16:30:50 INFO - PROCESS | 10149 | Values: 80.0 80.0 79.0 79.0 84.0 79.0 87.0 81.0 103.0 80.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:126.00 stddev:4.01 (3.2%) stddev-sans-first:4.24 16:30:50 INFO - PROCESS | 10149 | Values: 124.0 130.0 126.0 119.0 126.0 119.0 127.0 123.0 131.0 124.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.10 Median:160.50 stddev:1.91 (1.2%) stddev-sans-first:2.03 16:30:50 INFO - PROCESS | 10149 | Values: 160.0 158.0 161.0 158.0 163.0 160.0 159.0 161.0 163.0 158.0 16:30:50 INFO - PROCESS | 10149 | 16:30:50 INFO - PROCESS | 10149 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.84 (2.8%) stddev-sans-first:1.86 16:30:50 INFO - PROCESS | 10149 | Values: 66.0 67.0 63.0 66.0 64.0 66.0 64.0 64.0 63.0 61.0 16:30:50 INFO - PROCESS | 10149 | -------- Summary: end -------- 16:30:50 INFO - PROCESS | 10149 | 16:30:51 INFO - TEST-INFO | 10149: exit 0 16:30:51 INFO - TEST-OK | kraken | took 61413ms 16:30:51 INFO - SUITE-END | took 1176s 16:30:52 INFO - Completed test suite (00:19:36) 16:30:52 INFO - javascript benchmark 16:30:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3088.583041289426, 3106.285554322506, 3114.174743692921, 3133.9216519586994, 3164.794288371256, 5292.047431462345, 5349.919751203735, 5375.462453754622, 5447.373552041511, 5474.917876231854, 5316.122839731449, 5320.813771517997, 5331.813386531472, 5395.568354531638, 5448.210009548613, 4785.832495862637, 4856.125897338477, 4870.391201099863, 4872.537108974641, 5012.573931215851, 4931.161702510582, 4934.383202099738, 4987.725675087869, 5002.924956125661, 5016.749162541876, 4665.113628410591, 4723.126221649006, 4760.738159401231, 4820.855374338776, 4876.853694389174, 4845.055214233226, 4854.708717476963, 4855.805767769285, 4882.365292511986, 4974.278729584226, 4868.634852386085, 4882.560569548722, 4903.754812259373, 4914.582260507861, 4944.98627781304, 5238.030964271584, 5304.893902121975, 5365.973170134124, 5396.676199428047, 5409.43200963901, 5834.124881267801, 5834.183214349994, 5875.529957603381, 5886.470217680429, 5893.44012318829, 5850.765969361263, 5892.116182572606, 5892.263467066617, 5893.911591326134, 5910.231669882888, 5259.15853463444, 5391.406945236021, 5404.5946554008215, 5410.431904649955, 5477.370102438271, 5920.289565252111, 5928.555358348055, 5943.881122377528, 5945.10823376497, 5948.791792287336, 5736.569757268222, 5737.741801618937, 5738.110592858114, 5743.942560574341, 5747.166660834156, 5554.833354999357, 5608.831735047955, 5714.085746280575, 5714.085746280658, 5741.483266506035, 5730.0, 5730.627509211888, 5731.02572562657, 5732.111522713986, 5740.598158128891, 5585.413531579125, 5648.85877853057, 5697.686627235542, 5746.856328591819, 5756.740946657411, 4347.847825326098, 4352.107817897323, 4372.475302963601, 4376.124775045004, 4434.135343607965, 3909.843606255718, 3911.0573826534624, 3914.197589494147, 3946.2699400610977, 3953.9604603953594, 5393.784248630011, 5407.351117865881, 5409.864753381197, 5414.3232095988005, 5416.593755468356, 5584.748686309126, 5717.142428635738, 5759.0, 5759.337676167211, 5773.538116950634, 5401.0, 5401.648892821954, 5401.864953376197, 5402.675839449567, 5419.647722897999, 4832.44426890905, 4991.201407774739, 5002.949970500321, 5008.0, 5051.0, 5859.2090067841145, 5898.528117750569, 5906.645601263938, 5948.95893218685, 5949.3753155918, 5753.280839895013, 5757.65454072757, 5761.251037365116, 5763.769449221984, 5767.192593036894, 5842.883142337045, 5937.851553711192, 5939.138824870283, 5946.791862284972, 5959.28488581373, 5685.090385538294, 5737.971310143423, 5742.626729262584, 5747.166660834239, 5800.39095894925, 5776.826695199151, 5781.21953536284, 5808.796692115756, 5817.709114544171, 5834.562407819515, 5492.588055895903, 5604.103343465026, 5615.904898544744, 5690.430956904276, 5694.715264236855, 5642.351129620146, 5646.887062258814, 5651.519620832275, 5659.801906933238, 5688.317401911631, 5524.33707955048, 5537.473939975695, 5545.473180047889, 5555.527780138572, 5560.110382338807, 5461.890762184814, 5500.092484739973, 5651.237082993744, 5666.206731057738, 5731.197632331394, 5409.107497262908, 5418.647787893774, 5424.918626220689, 5431.429699881455, 5475.808346707846], "value": 5302.237987594498, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.3569138256537, 609.3997412548639, 614.2966303582401, 640.2988727343558, 641.1472741254131, 31241.375172496537, 42996.0, 44006.339904901455, 44035.77982110085, 44102.779486102525, 45278.54721452785, 45465.7726711366, 45775.0, 45872.541274587245, 45985.0, 8047.155048719888, 8854.91145088549, 9070.092990700943, 10839.078678312313, 11147.498362573662, 10532.42071686057, 10912.181586381013, 10916.07213386863, 10956.12351011917, 10962.177836662242, 10915.181361397927, 10964.506597203144, 10966.232363734542, 10980.505877235544, 10983.560657573687, 10816.513256903458, 10887.0, 10905.890941090567, 10912.181586381013, 10913.454327283604, 10867.130629549618, 10930.781384372269, 10944.671659850217, 10959.397233152173, 10989.505472253768, 43826.0, 44195.77902110501, 44234.77882610566, 44318.33522497133, 44381.33427998583, 3493.2314890724, 3626.202235508184, 3672.559292884844, 3689.6494832991093, 3743.1765011697385, 32995.6700432995, 33318.6668133318, 33520.0, 34532.44603992826, 34758.82620586906, 4431.512533621299, 4455.287154055336, 4483.843065492692, 4496.392986946753, 4496.460424749019, 341633.0, 341661.0, 343316.0, 344054.27972860227, 344867.0, 63848.68075659592, 64153.35846641567, 64211.35788642147, 64289.0, 64349.0, 67010.32989670138, 67080.66459667769, 67091.32908670949, 67147.0, 67186.64846730439, 17083.231254593575, 17809.376671816684, 17824.91087544554, 17832.732509012374, 17835.46493605194, 13666.863331366758, 13730.176189428666, 13736.244506552035, 13739.381727822272, 13749.587512374645, 19296.903515482612, 20198.899005504878, 20496.18015279372, 20633.277835275694, 20666.689999650018, 7261.346478816932, 7495.775126746206, 7612.505187162817, 7663.808404789925, 7697.923020769833, 913.4017218721748, 1014.8934361891895, 1021.6424251511911, 1102.7463683352873, 1133.2350663302402, 32384.190395239835, 32473.188170295933, 32607.021789346356, 32908.67091329104, 33029.50455743166, 883.5689019566877, 1079.1205167788228, 1098.6429410441638, 1137.6814491942268, 1176.8352430659716, 16800.15999200035, 16997.575060623585, 17102.572435689206, 17145.59865665815, 17385.04382258988, 532821.0, 533498.0, 533743.0, 537483.0, 537714.0, 59225.0, 59840.40159598435, 59850.40149598535, 59900.40099598948, 59927.40072599305, 1212.2847519963263, 1338.9263590502617, 1354.0724603646534, 1362.2439546052037, 1372.0807059270312, 32156.517652235703, 33382.66617333796, 33441.33117337591, 33860.0, 34406.82796586001, 49148.508514915826, 49769.25346119884, 49820.50179498159, 49915.0, 49941.500584993686, 417592.208350631, 417618.0, 418488.0, 419475.0, 420686.0, 33555.49666755049, 43460.130797383244, 44327.556724433634, 46968.530314696414, 48001.0, 47837.0, 48038.27942580934, 48185.27722084102, 48947.755261223465, 50605.24092138547, 40062.0, 42342.57657423386, 42345.0, 42721.78639106784, 42782.78608607061, 40457.39313910352, 40814.79592602137, 41008.17983640251, 41629.375559366024, 41646.37530437107, 42243.0, 42753.5724642762, 43048.354274685276, 43292.56707432885, 43329.35005974976, 34807.30385392329, 36977.26045479129, 37008.38104555885, 37755.43366849554, 37893.24213515659, 26870.32824179411, 27155.45689086168, 27159.86420067887, 27221.86389068042, 27464.313392165357], "value": 21252.616749357425, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.86092715231797, 374.4103037715598, 384.5462354421781, 387.9398693202553, 400.6754528831645, 18179.545511362205, 18913.24347026117, 19121.139548720304, 19671.50821229468, 19675.606487870235, 18505.814941850578, 18535.351262705808, 18832.34086806962, 18832.811671883275, 18837.62324753504, 5432.67403955762, 5442.156465747816, 5639.718014099279, 5668.093105103184, 5668.546516278709, 5609.242752228438, 5691.857703557403, 5718.313802343725, 5728.312602487707, 5732.856678583048, 5491.807786727465, 5659.179418984245, 5699.772009119631, 5704.372519022904, 5706.3722990471015, 5744.454276843693, 5753.942460575383, 5778.508826749731, 5806.65160090396, 5807.070868661015, 5518.944810551923, 5541.347395289709, 5611.775528978836, 5663.7168141593165, 5680.31836179657, 21652.675209871864, 22212.222572209896, 22365.10539578415, 22429.55140897173, 22830.77169228303, 2237.4182712494708, 2286.7255929288426, 2315.9189428369928, 2575.22743177048, 2596.675415573053, 9409.153176214173, 9677.612895484172, 9953.25350598708, 10291.125254353388, 10522.579096836118, 1916.7604049493814, 1942.80571942806, 1994.044665012407, 2019.989900050505, 2030.0661695620033, 84739.0, 85071.57464212639, 85565.0, 86771.56614216951, 88483.55758221232, 10741.731456713645, 10845.620403285848, 10861.891381086247, 10883.945580272206, 11046.447677616086, 14764.0, 14969.101853888802, 15014.324355404033, 15019.924900375428, 15022.474213402478, 7726.266004729542, 7859.4891332063235, 7965.6017199139815, 8001.079875814239, 8168.509889406655, 7503.7748867534065, 7540.660670269741, 7657.272559106876, 7755.961220193863, 7784.649690763929, 4536.9092618148115, 4625.4911959684405, 4654.301854721751, 4670.416197975253, 4808.158572249842, 1854.3509771579838, 1892.5174080609631, 1918.5779128591687, 1920.8943508107188, 1978.8317992970472, 880.6873559886276, 928.3455164109287, 978.6574698855344, 1005.9346142500714, 1021.1371391174446, 18520.536986575444, 18824.529386765167, 19685.0, 19715.704264435757, 20243.696344554843, 1006.3710181136789, 1006.6476733143488, 1006.6979906028163, 1013.0882206014646, 1045.5869931377063, 9173.81652366949, 10666.679999600025, 10884.72788180286, 10916.181286403551, 11030.06244469229, 357433.0, 359949.0, 363816.0, 448580.0, 457207.0, 91661.54169229245, 94810.52594736982, 102284.48857755664, 102946.48526757318, 103977.0, 1015.5836107196162, 1051.4112097225577, 1053.847192157133, 1064.302881612545, 1074.1889873145724, 17306.653866922843, 17564.73652895182, 17818.10909454496, 17874.106294684952, 18092.095395229924, 41584.376234357114, 42525.57474425216, 42907.570924290354, 43257.56742432661, 44129.92505861867, 59559.404405955385, 59928.70035649794, 60394.698026509584, 61508.384916150266, 63233.0, 7184.425245980414, 7281.708731650675, 7527.209642987407, 7569.5079819811535, 7614.124375696866, 7751.263629955145, 10563.207759418228, 12030.398480076135, 13217.933910330386, 13604.25176615256, 11946.104042196695, 11955.342456164994, 12030.27818330903, 12142.210756300812, 12219.26684398939, 5966.850828729316, 10870.45647717595, 11410.372429516456, 11450.656480305604, 12090.093243006633, 10679.946600266949, 10994.175436842108, 11850.762984740084, 12125.713431198923, 12314.445849936954, 7597.582132982518, 7598.468107232441, 7620.3522700571075, 7695.4997925134685, 7852.097008843828, 10042.648507302209, 11969.102317326062, 11999.880001199876, 12080.335581542748, 12162.635120946386], "value": 9046.795409672666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1234.8888600025996, 1469.9485518006868, 1487.048289094979, 1622.302409963716, 1759.7888253409592, 7700.845983080342, 8042.879356809653, 8044.5575493347915, 8335.249827515523, 8352.749417517483, 8001.839963200732, 8260.545669988149, 8262.256396924276, 8263.83472330553, 8266.256036956673, 1957.1290775604791, 2212.893553223388, 2252.1892118837245, 2282.7032485776826, 2293.3234695764704, 2292.805111565519, 2297.3682237384687, 2298.5977453945493, 2300.298408985259, 2303.9654405183937, 2226.387743370578, 2282.2354511238755, 2283.0, 2283.600369935263, 2289.290320000805, 1931.449536881989, 2188.529466164782, 2277.1119263487253, 2280.6579013147993, 2296.781805728453, 2214.3910424633195, 2234.6648002799548, 2239.585676649825, 2240.0703707961175, 2242.742084660252, 8374.0, 8400.495970241744, 8438.240558349778, 8439.915600843975, 8449.112843151443, 4866.878328041792, 4993.800247990076, 4996.800127994876, 4996.875078123039, 5003.649744517848, 3227.451333273326, 3313.950290745641, 3323.401787678217, 3357.4963755436634, 3370.4775759757276, 1011.1102230037593, 1049.6588608702125, 1059.3220338983058, 1062.1534288107869, 2076.200662744839, 11390.943045284721, 11515.30908145514, 11521.251118677259, 11533.711657208552, 11545.942270288679, 4080.571539988318, 4233.216854881856, 4294.162638285567, 4295.52749197588, 4316.913661726748, 9150.130737579919, 9222.446653200695, 9225.169734723908, 9225.354225204306, 9226.077392260855, 4437.733735975852, 4931.0630980113665, 4946.579540739005, 4947.0, 4950.8267210647455, 4204.474440694913, 4259.637930775919, 4276.273033584298, 4323.63249123828, 4487.057717879282, 3625.528681271418, 3644.908877278036, 3701.833417496219, 3755.849766009329, 3758.229562939591, 1087.2497976396262, 1142.731458107344, 1146.5929594993656, 1147.145376694368, 1157.791597512439, 904.1817155474306, 909.6977969969103, 1002.4987506246877, 1032.3702541449709, 1034.2139973620103, 5770.42295770428, 5848.561357898089, 5854.443827836349, 5946.524278057745, 5966.164736936832, 960.86547883295, 961.6874515782399, 1003.8494225866178, 1014.0619926568043, 1015.8374660054355, 4063.3904914262507, 4156.501219853637, 4240.660747140221, 4255.489341279066, 4264.744115353026, 10095.899041009643, 10310.432926188982, 10454.581816727397, 10477.161827053817, 10478.37129772216, 9674.0, 10230.488475576192, 10376.636817711495, 10381.429021403896, 10396.48017599132, 7523.510971786816, 7811.570363629941, 7919.0, 7919.960400198077, 7932.484388514844, 5692.857678558069, 5840.649561026182, 5974.790882319098, 6084.482818960438, 6122.326544080237, 9404.811903761749, 9463.242940564876, 9517.619295228113, 9531.428114313163, 9538.046195380406, 9257.86113208289, 9634.325597208128, 9718.140231058676, 9813.460259685786, 9852.950735246564, 6941.444684425132, 7421.18366979621, 7616.0, 7628.80927976805, 7661.846763064596, 8344.833103337778, 8349.83300334002, 8398.328133749408, 8406.411551191359, 8436.662533498591, 7258.0927384076995, 7305.926940730525, 7445.292697193758, 7569.924300756922, 7614.086309642878, 7250.891236631349, 7300.12398512182, 7388.593627350548, 7435.107787065586, 7526.548407095592, 6661.866762664623, 6662.83342916431, 6726.764563240263, 6729.596224226562, 6732.629705366252, 4904.754762261944, 5075.7969681212335, 5091.13450713387, 5120.385553733576, 5149.639525233198, 4982.4768399319, 5127.051495473349, 5134.255532947776, 5227.660202086852, 5285.445028271976], "value": 4467.301577089666, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.62328893683116, 315.3252040633045, 323.50019220304637, 329.3166679140783, 330.17127011201893, 5177.326947496829, 5281.86795330116, 5348.609361565992, 5392.811251606195, 5405.972970135153, 5290.68255904646, 5308.5487733542595, 5320.334958130234, 5336.706481143545, 5354.330708661418, 4198.9370159447635, 4201.655470249514, 4203.85286514972, 4204.939579605986, 4209.936850947238, 3945.7435266707716, 3981.422693709425, 4167.604077612637, 4227.196832601796, 4235.555266696999, 4104.363823607346, 4140.958590414088, 4142.565030671782, 4162.916741665165, 4175.6033176848305, 4102.200070986159, 4122.196171746511, 4138.213739389536, 4138.592878421351, 4148.709590328678, 3473.947890781615, 3681.1349332906743, 3700.481932529448, 3702.611225821304, 3705.203381273039, 5176.327077479941, 5187.0922588546855, 5196.506331898464, 5220.817271395483, 5231.790728370861, 4739.905201895977, 4740.336352910595, 4777.402824646919, 4783.449903261135, 4785.976070119662, 5443.945560544423, 5537.612367134302, 5565.332160140769, 5565.833025009256, 5586.0, 3941.507311586052, 3950.3876899080688, 3952.545457272393, 3955.367141257414, 3970.8014599269923, 5767.452092051207, 5789.16057171712, 5797.681127537984, 5815.970920145414, 5828.475437210629, 3860.247251785931, 4042.4744783178, 4077.959220407758, 4078.46979892618, 4088.039310762028, 4073.6944729145434, 4079.8164082616354, 4084.7753373564738, 4086.1827634473225, 4091.9590804091577, 4660.440747110369, 4665.720056796603, 4678.368420263289, 4688.906221875543, 4698.154332220233, 5415.431379705153, 5520.861978450571, 5554.389017208104, 5554.583406244547, 5559.471850174227, 1410.3441899516774, 1435.7272118297699, 1436.727021865842, 1439.4242303078852, 1440.9985060383142, 891.9108089191029, 901.9594118264694, 921.7603423109906, 924.4453328003278, 928.6053427293373, 4742.932840110948, 4766.547178018082, 4849.806007759721, 4875.341828853059, 4886.118844244991, 5035.590034790264, 5063.822766203239, 5128.692278463304, 5131.204663277198, 5144.022635699205, 4518.6728834650185, 4835.8065677373215, 4860.517542149678, 4867.221244600847, 4906.681065730716, 2715.769159621415, 2731.7814574833965, 2738.342797728531, 2739.7534121974454, 2756.572731226663, 5212.244224587489, 5233.278695078538, 5246.23929530216, 5256.39551451588, 5280.155175172031, 4821.807127714922, 5185.118529849935, 5195.246689230041, 5221.712805795718, 5222.634415590872, 5166.483351664879, 5180.119379705459, 5205.323307970016, 5213.478652134756, 5255.214736189825, 5050.3434553507805, 5058.822941197041, 5078.441371449211, 5095.77069031887, 5154.304168937296, 4833.419989601271, 4928.704277743347, 5003.574696150722, 5041.159976608585, 5066.392032956069, 5199.29809475716, 5245.295279034252, 5248.475152484721, 5249.895002100013, 5257.842264731911, 3819.942700859545, 3848.615138486129, 4083.775392353449, 4140.503139623264, 4243.087736136746, 5722.856928576819, 5724.570657200643, 5758.1362795581, 5761.8559536011935, 5793.478586927196, 5467.507924286833, 5607.327120745374, 5610.719464026701, 5617.438256174513, 5622.437756224344, 5605.439456054362, 5613.989481893298, 5615.213870058276, 5643.576731745054, 5666.186820217491, 5526.088195447867, 5542.806001789918, 5555.2778138841695, 5555.555555555627, 5561.277033985556, 5425.186222066722, 5644.322681278273, 5647.830565083054, 5670.858228544319, 5685.0, 5451.37309209446, 5480.3971563127225, 5493.395726470165, 5497.642653227527, 5506.146547285177], "value": 4113.601746130815, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.7858335108149, 763.0690557519824, 776.2315307845233, 786.6263524825708, 789.8696715042018, 14383.640408989768, 14659.0, 14898.702025959488, 15851.034620472745, 16671.333146674147, 15799.131047792367, 15976.600584985352, 15994.520164395057, 16004.199790010482, 16018.83981160188, 801.4029547986758, 804.1797366685977, 808.5027707959592, 808.6320724070563, 809.1180613131685, 667.8864593019174, 684.0252639988022, 791.9881201781977, 810.1817164663673, 817.1379194949326, 832.5421018439864, 832.9333653307721, 836.3518273338182, 836.8368168207202, 837.1962915601, 833.5248908122372, 834.6202477872583, 835.1231207232381, 835.5237514616671, 835.7534527314457, 510.7344181025846, 525.1073175601455, 593.2554643921893, 611.3275397063239, 616.7995401494533, 14485.927570362186, 14641.19473428961, 14712.779308310384, 14740.336684849208, 14808.851911480855, 12933.224006559636, 13512.662183445394, 13530.323483825769, 13549.86450135496, 13792.207792207691, 12055.276683399024, 12340.321282329469, 12341.233924468583, 12457.128001039931, 12894.097413181082, 4431.0, 4480.0, 4540.614047805908, 4673.906521869578, 4687.484376718592, 74513.0, 74722.62638686826, 74958.62520687416, 77465.0, 77546.99999999943, 10157.847632285522, 10326.432046237529, 10441.269111162146, 10600.836986847287, 10662.520186591622, 18132.546686332946, 18146.63706725858, 18150.909245453688, 18151.455456336065, 18171.63656726858, 8139.633716482773, 8151.83696326082, 8216.630251638691, 8221.054578723404, 8422.957885210657, 4481.417415735934, 4647.767611619405, 4704.764761761967, 4770.72214355733, 4783.832565860178, 3311.817850018224, 3457.0493114393744, 3468.9826550867588, 3470.4447288434235, 3473.704735097545, 642.2870613618879, 651.221789960992, 659.4856012310405, 661.4014317042987, 663.5454713521157, 628.0829988217163, 704.2957042957042, 765.9846803064019, 787.0948409329203, 820.1429506166071, 9577.712668619952, 9685.25902378899, 9749.463779492196, 9826.164775993977, 9856.05134020326, 625.4589779840496, 705.9047028651066, 737.1964558631117, 759.1649185895582, 772.212343409719, 4394.934075988863, 4844.515548445198, 4883.902321953541, 4885.8289959851945, 4899.216125419915, 110893.44553277343, 112664.0, 112947.0, 113473.43263283631, 114639.42680286712, 97891.51054244826, 98010.0, 99873.0, 101024.0, 101156.0, 55540.444595554334, 86685.0, 88758.55620721984, 88810.11189888019, 89226.10773892308, 8781.604827782892, 9217.354785164975, 9362.90354762773, 9421.717348479557, 9449.149576538144, 52321.73839130932, 52585.7370713144, 52718.04614653839, 53946.730266348415, 54226.45773542214, 63720.362796371446, 72550.63724681342, 73924.84187027162, 77010.6149469249, 79236.60381698054, 13092.214467132002, 13239.536616218385, 13618.114822536503, 13654.249016304198, 13709.931450342685, 15050.473233436776, 15215.923920380328, 15306.846931530989, 15384.307706153026, 15685.843141568439, 12763.298018608693, 13162.868371316546, 14476.710465790837, 14612.926935365254, 15122.924385378003, 13282.933585332397, 13327.466901323838, 13645.454181833013, 14031.719365612427, 15187.696246075238, 13639.386227619583, 13685.0, 13840.051030578785, 14165.362558684677, 15020.699586008437, 10704.839427408438, 10909.39998300101, 10917.563297468012, 11300.604478843201, 11319.547218111184, 10931.344119352865, 11472.483738231633, 11897.0, 11921.105917056082, 11933.940330298641], "value": 7540.746714313106, "unit": "score"}], "name": "dromaeo_css", "value": 7216.8828105625835, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 113.0, 110.0, 109.0, 111.0, 110.0, 109.0, 110.0, 110.0, 110.0], "value": 109.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 126.0, 128.0, 180.0, 126.0, 127.0, 161.0, 124.0, 162.0, 128.0], "value": 141.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 160.0, 160.0, 162.0, 161.0, 162.0, 164.0, 164.0], "value": 161.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 77.0, 109.0, 74.0, 75.0, 76.0, 75.0, 103.0, 76.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 88.0, 116.0, 86.0, 86.0, 86.0, 130.0, 86.0, 86.0, 127.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 145.0, 145.0, 145.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 52.0, 54.0, 55.0, 53.0, 56.0, 53.0, 53.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 63.0, 64.0, 65.0, 63.0, 63.0, 64.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 79.0, 79.0, 84.0, 79.0, 87.0, 81.0, 103.0, 80.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 130.0, 126.0, 119.0, 126.0, 119.0, 127.0, 123.0, 131.0, 124.0], "value": 124.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 158.0, 161.0, 158.0, 163.0, 160.0, 159.0, 161.0, 163.0, 158.0], "value": 160.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 63.0, 66.0, 64.0, 66.0, 64.0, 64.0, 63.0, 61.0], "value": 64.4, "unit": "score"}], "name": "kraken", "value": 1500.1000000000001, "alertThreshold": 2.0}]} 16:30:52 INFO - Return code: 0 16:30:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:30:52 INFO - # TBPL SUCCESS # 16:30:52 INFO - Running post-action listener: _resource_record_post_action 16:30:52 INFO - ##### 16:30:52 INFO - ##### Finished run-tests step (success) 16:30:52 INFO - ##### 16:30:52 INFO - Running post-run listener: _resource_record_post_run 16:30:52 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 4096; Write bytes: 498401280; Read time: 20; Write time: 524572 16:30:52 INFO - TinderboxPrint: CPU usage
13.3% 16:30:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 16:30:52 INFO - TinderboxPrint: I/O write bytes / time
498,401,280 / 524,572 16:30:52 INFO - TinderboxPrint: CPU idle
8,267.7 (86.7%) 16:30:52 INFO - TinderboxPrint: CPU user
1,217.3 (12.8%) 16:30:52 INFO - TinderboxPrint: Swap in / out
0 / 0 16:30:52 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 203522048; Read time: 0; Write time: 241248 16:30:52 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 4096; Write bytes: 294879232; Read time: 20; Write time: 283324 16:30:52 INFO - Running post-run listener: _upload_blobber_files 16:30:52 INFO - Blob upload gear active. 16:30:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:30:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:30:53 INFO - (blobuploader) - INFO - Open directory for files ... 16:30:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:30:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:30:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:54 INFO - (blobuploader) - INFO - Done attempting. 16:30:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:30:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:30:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:54 INFO - (blobuploader) - INFO - Done attempting. 16:30:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:30:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:30:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:58 INFO - (blobuploader) - INFO - Done attempting. 16:30:58 INFO - (blobuploader) - INFO - Iteration through files over. 16:30:58 INFO - Return code: 0 16:30:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:30:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:30:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53e94ce1a6815bc2d691710b86978089366b62eba3e5ba0257d9d265090c8ad26217c504ffab649106593316bcc7d356f726ec5171fa2157aeb1108316d33ee2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a262e4d3678064444746a5726c7e4a8ceea49a0e900b673e704be9178f74dea397adac478cb777f6becc959c2c983ad316d8b3d72ffcc22f2c31f62c55313a0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9f257b8e53d762414c0a45bad5c61fa18e526ff97991647f29248ac67d467e0d5088982edc0d735c5c04b4e3284276f350f7c64f0cfa4ff66ebc39f9cb2efeb"} 16:30:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:30:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:30:58 INFO - Contents: 16:30:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53e94ce1a6815bc2d691710b86978089366b62eba3e5ba0257d9d265090c8ad26217c504ffab649106593316bcc7d356f726ec5171fa2157aeb1108316d33ee2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a262e4d3678064444746a5726c7e4a8ceea49a0e900b673e704be9178f74dea397adac478cb777f6becc959c2c983ad316d8b3d72ffcc22f2c31f62c55313a0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9f257b8e53d762414c0a45bad5c61fa18e526ff97991647f29248ac67d467e0d5088982edc0d735c5c04b4e3284276f350f7c64f0cfa4ff66ebc39f9cb2efeb"} 16:30:58 INFO - Running post-run listener: copy_logs_to_upload_dir 16:30:58 INFO - Copying logs to upload dir... 16:30:58 INFO - mkdir: /builds/slave/test/build/upload/logs 16:30:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1243.247465 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 43 secs) (at 2016-06-27 16:30:57.796024) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 16:30:57.799452) ========= 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=0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53e94ce1a6815bc2d691710b86978089366b62eba3e5ba0257d9d265090c8ad26217c504ffab649106593316bcc7d356f726ec5171fa2157aeb1108316d33ee2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a262e4d3678064444746a5726c7e4a8ceea49a0e900b673e704be9178f74dea397adac478cb777f6becc959c2c983ad316d8b3d72ffcc22f2c31f62c55313a0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9f257b8e53d762414c0a45bad5c61fa18e526ff97991647f29248ac67d467e0d5088982edc0d735c5c04b4e3284276f350f7c64f0cfa4ff66ebc39f9cb2efeb"} build_url:https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011269 build_url: 'https://queue.taskcluster.net/v1/task/GqEFZemJTayAa5lAcqhC6Q/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/53e94ce1a6815bc2d691710b86978089366b62eba3e5ba0257d9d265090c8ad26217c504ffab649106593316bcc7d356f726ec5171fa2157aeb1108316d33ee2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a262e4d3678064444746a5726c7e4a8ceea49a0e900b673e704be9178f74dea397adac478cb777f6becc959c2c983ad316d8b3d72ffcc22f2c31f62c55313a0e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f9f257b8e53d762414c0a45bad5c61fa18e526ff97991647f29248ac67d467e0d5088982edc0d735c5c04b4e3284276f350f7c64f0cfa4ff66ebc39f9cb2efeb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-27 16:30:57.828650) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 16:30:57.829425) ========= 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=0e2a256762fed810e2f0f810000001b3-1467068700.751166-137916558 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004463 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-27 16:30:57.913482) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 16:30:57.913827) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-27 16:30:57.914501) ========= ========= Total master_lag: 0.22 =========