builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-069 starttime: 1468591231.98 results: success (0) buildid: 20160714105442 builduid: 3ade22f8d84e464683efb036733ff64d revision: 2299c291b4398bbb8caa79bfc6064102e053e3ff ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:31.983096) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:31.983600) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:31.983936) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:32.240690) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:32.241103) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-15 07:00:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.3M=0s 2016-07-15 07:00:32 (40.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.217859 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:32.502510) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:32.503284) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.194420 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:32.712435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 07:00:32.712852) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2299c291b4398bbb8caa79bfc6064102e053e3ff --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2299c291b4398bbb8caa79bfc6064102e053e3ff --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=05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-15 07:00:32,850 truncating revision to first 12 chars 2016-07-15 07:00:32,850 Setting DEBUG logging. 2016-07-15 07:00:32,850 attempt 1/10 2016-07-15 07:00:32,850 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2299c291b439?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 07:00:33,752 unpacking tar archive at: mozilla-aurora-2299c291b439/testing/mozharness/ program finished with exit code 0 elapsedTime=1.235886 ========= master_lag: 0.24 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-15 07:00:34.184393) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:34.184756) ========= script_repo_revision: 2299c291b4398bbb8caa79bfc6064102e053e3ff ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:34.185213) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:34.185512) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 07:00:34.196997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-15 07:00:34.197309) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:00:34 INFO - MultiFileLogger online at 20160715 07:00:34 in /builds/slave/test 07:00:34 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 07:00:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:00:34 INFO - {'append_to_log': False, 07:00:34 INFO - 'base_work_dir': '/builds/slave/test', 07:00:34 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:00:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:00:34 INFO - 'branch': 'Mozilla-Aurora', 07:00:34 INFO - 'buildbot_json_path': 'buildprops.json', 07:00:34 INFO - 'config_files': ('talos/linux_config.py',), 07:00:34 INFO - 'default_actions': ('clobber', 07:00:34 INFO - 'read-buildbot-config', 07:00:34 INFO - 'download-and-extract', 07:00:34 INFO - 'populate-webroot', 07:00:34 INFO - 'create-virtualenv', 07:00:34 INFO - 'install', 07:00:34 INFO - 'run-tests'), 07:00:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:00:34 INFO - 'download_minidump_stackwalk': True, 07:00:34 INFO - 'download_symbols': 'ondemand', 07:00:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:00:34 INFO - 'tooltool.py': '/tools/tooltool.py', 07:00:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:00:34 INFO - '/tools/misc-python/virtualenv.py')}, 07:00:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:00:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:00:34 INFO - 'installer_path': 'installer.exe', 07:00:34 INFO - 'log_level': 'info', 07:00:34 INFO - 'log_name': 'talos', 07:00:34 INFO - 'log_to_console': True, 07:00:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:00:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:00:34 INFO - 'opt_config_files': (), 07:00:34 INFO - 'pip_index': False, 07:00:34 INFO - 'sps_profile': False, 07:00:34 INFO - 'sps_profile_interval': 0, 07:00:34 INFO - 'suite': 'dromaeojs', 07:00:34 INFO - 'system_bits': '32', 07:00:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:00:34 INFO - 'title': 'talos-linux64-ix-069', 07:00:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:00:34 INFO - 'use_talos_json': True, 07:00:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:00:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:00:34 INFO - 'work_dir': 'build'} 07:00:34 INFO - ##### 07:00:34 INFO - ##### Running clobber step. 07:00:34 INFO - ##### 07:00:34 INFO - Running pre-action listener: _resource_record_pre_action 07:00:34 INFO - Running main action method: clobber 07:00:34 INFO - rmtree: /builds/slave/test/build 07:00:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:00:35 INFO - Running post-action listener: _resource_record_post_action 07:00:35 INFO - ##### 07:00:35 INFO - ##### Finished clobber step (success) 07:00:35 INFO - ##### 07:00:35 INFO - ##### 07:00:35 INFO - ##### Running read-buildbot-config step. 07:00:35 INFO - ##### 07:00:35 INFO - Running pre-action listener: _resource_record_pre_action 07:00:35 INFO - Running main action method: read_buildbot_config 07:00:35 INFO - Using buildbot properties: 07:00:35 INFO - { 07:00:35 INFO - "project": "", 07:00:35 INFO - "product": "firefox", 07:00:35 INFO - "script_repo_revision": "production", 07:00:35 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:00:35 INFO - "repository": "", 07:00:35 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:00:35 INFO - "buildid": "20160714105442", 07:00:35 INFO - "pgo_build": "True", 07:00:35 INFO - "basedir": "/builds/slave/test", 07:00:35 INFO - "buildnumber": 415, 07:00:35 INFO - "slavename": "talos-linux64-ix-069", 07:00:35 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 07:00:35 INFO - "platform": "ubuntu64_hw", 07:00:35 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:00:35 INFO - "branch": "mozilla-aurora", 07:00:35 INFO - "repo_path": "releases/mozilla-aurora", 07:00:35 INFO - "revision": "2299c291b4398bbb8caa79bfc6064102e053e3ff", 07:00:35 INFO - "stage_platform": "linux64-pgo", 07:00:35 INFO - "builduid": "3ade22f8d84e464683efb036733ff64d", 07:00:35 INFO - "slavebuilddir": "test" 07:00:35 INFO - } 07:00:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 07:00:35 INFO - Running post-action listener: _resource_record_post_action 07:00:35 INFO - ##### 07:00:35 INFO - ##### Finished read-buildbot-config step (success) 07:00:35 INFO - ##### 07:00:35 INFO - ##### 07:00:35 INFO - ##### Running download-and-extract step. 07:00:35 INFO - ##### 07:00:35 INFO - Running pre-action listener: _resource_record_pre_action 07:00:35 INFO - Running main action method: download_and_extract 07:00:35 INFO - mkdir: /builds/slave/test/build/tests 07:00:35 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')]} 07:00:35 INFO - https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:00:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:00:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:00:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:00:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 07:00:36 INFO - Downloaded 1361 bytes. 07:00:36 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:00:36 INFO - Using the following test package requirements: 07:00:36 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 07:00:36 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:00:36 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:00:36 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'jsshell-linux-x86_64.zip'], 07:00:36 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:00:36 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 07:00:36 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:00:36 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:00:36 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:00:36 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:00:36 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:00:36 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:00:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:36 INFO - https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:00:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:00:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:00:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:00:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:00:37 INFO - Downloaded 24491003 bytes. 07:00:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:00:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:00:38 INFO - Return code: 0 07:00:39 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 07:00: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')]} 07:00:39 INFO - https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:00:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:00:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:00:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 07:00:40 INFO - Downloaded 24491003 bytes. 07:00:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:00:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 07:00:42 INFO - Return code: 0 07:00: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')]} 07:00:42 INFO - https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:00:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:00:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:00:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:00:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 07:00:43 INFO - Downloaded 10833369 bytes. 07:00:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 07:00:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 07:00:43 INFO - Return code: 0 07:00:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:43 INFO - https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:00:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:00:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:00:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:00:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 07:00:44 INFO - Downloaded 63636953 bytes. 07:00:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:00:44 INFO - mkdir: /builds/slave/test/properties 07:00:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:00:44 INFO - Writing to file /builds/slave/test/properties/build_url 07:00:44 INFO - Contents: 07:00:44 INFO - build_url:https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - Running post-action listener: set_extra_try_arguments 07:00:45 INFO - ##### 07:00:45 INFO - ##### Finished download-and-extract step (success) 07:00:45 INFO - ##### 07:00:45 INFO - ##### 07:00:45 INFO - ##### Running populate-webroot step. 07:00:45 INFO - ##### 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: populate_webroot 07:00:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:00:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:00:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:00:45 INFO - '--tppagecycles', 07:00:45 INFO - '7'], 07:00:45 INFO - 'tests': ['tsvgm']}}, 07:00:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:00:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:00:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:00:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:00:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:00:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['damp', 'tps']}, 07:00:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'tests': ['damp', 'tps']}, 07:00:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['dromaeo_dom']}, 07:00:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:00:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['a11yr', 07:00:45 INFO - 'ts_paint', 07:00:45 INFO - 'tpaint', 07:00:45 INFO - 'sessionrestore', 07:00:45 INFO - 'sessionrestore_no_auto_restore', 07:00:45 INFO - 'tabpaint']}, 07:00:45 INFO - 'other-e10s': {'tests': ['a11yr', 07:00:45 INFO - 'ts_paint', 07:00:45 INFO - 'tpaint', 07:00:45 INFO - 'sessionrestore', 07:00:45 INFO - 'sessionrestore_no_auto_restore', 07:00:45 INFO - 'tabpaint']}, 07:00:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:00:45 INFO - 'ts_paint', 07:00:45 INFO - 'tpaint', 07:00:45 INFO - 'sessionrestore', 07:00:45 INFO - 'sessionrestore_no_auto_restore', 07:00:45 INFO - 'tabpaint']}, 07:00:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:00:45 INFO - 'ts_paint', 07:00:45 INFO - 'tpaint', 07:00:45 INFO - 'sessionrestore', 07:00:45 INFO - 'sessionrestore_no_auto_restore', 07:00:45 INFO - 'tabpaint']}, 07:00:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['a11yr', 07:00:45 INFO - 'ts_paint', 07:00:45 INFO - 'tpaint', 07:00:45 INFO - 'sessionrestore', 07:00:45 INFO - 'sessionrestore_no_auto_restore', 07:00:45 INFO - 'tabpaint']}, 07:00:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['a11yr', 07:00:45 INFO - 'ts_paint', 07:00:45 INFO - 'tpaint', 07:00:45 INFO - 'sessionrestore', 07:00:45 INFO - 'sessionrestore_no_auto_restore', 07:00:45 INFO - 'tabpaint']}, 07:00:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['tsvgx', 07:00:45 INFO - 'tsvgr_opacity', 07:00:45 INFO - 'tart', 07:00:45 INFO - 'tscrollx', 07:00:45 INFO - 'cart']}, 07:00:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:00:45 INFO - 'tsvgr_opacity', 07:00:45 INFO - 'tart', 07:00:45 INFO - 'tscrollx', 07:00:45 INFO - 'cart']}, 07:00:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'talos_options': ['--disable-e10s'], 07:00:45 INFO - 'tests': ['tp5o']}, 07:00:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'tests': ['tp5o']}, 07:00:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'talos_options': ['--disable-e10s', 07:00:45 INFO - '--xperf_path', 07:00:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:45 INFO - 'tests': ['tp5n']}, 07:00:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:00:45 INFO - 'talos_options': ['--xperf_path', 07:00:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:00:45 INFO - 'tests': ['tp5n']}}, 07:00:45 INFO - 'talos.zip': {'path': '', 07:00:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:00:45 INFO - Running post-action listener: _resource_record_post_action 07:00:45 INFO - ##### 07:00:45 INFO - ##### Finished populate-webroot step (success) 07:00:45 INFO - ##### 07:00:45 INFO - ##### 07:00:45 INFO - ##### Running create-virtualenv step. 07:00:45 INFO - ##### 07:00:45 INFO - Running pre-action listener: _resource_record_pre_action 07:00:45 INFO - Running main action method: create_virtualenv 07:00:45 INFO - Creating virtualenv /builds/slave/test/build/venv 07:00:45 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 07:00:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:00:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:00:46 INFO - Using real prefix '/usr' 07:00:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:00:47 INFO - Installing distribute.............................................................................................................................................................................................done. 07:00:49 INFO - Installing pip.................done. 07:00:49 INFO - Return code: 0 07:00:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:00: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')]} 07:00:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00: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')]} 07:00:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:49 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:00:49 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 07:00:49 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 07:00:49 INFO - Using env: {'DISPLAY': ':0', 07:00:49 INFO - 'HOME': '/home/cltbld', 07:00:49 INFO - 'LANG': 'en_US.UTF-8', 07:00:49 INFO - 'LANGUAGE': 'en_US:en', 07:00:49 INFO - 'LOGNAME': 'cltbld', 07:00:49 INFO - 'MAIL': '/var/mail/cltbld', 07:00:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:49 INFO - 'MOZ_NO_REMOTE': '1', 07:00:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:49 INFO - 'NO_EM_RESTART': '1', 07:00:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:49 INFO - 'PWD': '/builds/slave/test', 07:00:49 INFO - 'SHELL': '/bin/bash', 07:00:49 INFO - 'SHLVL': '1', 07:00:49 INFO - 'TERM': 'linux', 07:00:49 INFO - 'TMOUT': '86400', 07:00:49 INFO - 'USER': 'cltbld', 07:00:49 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:00:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:49 INFO - '_': '/tools/buildbot/bin/python'} 07:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:50 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 07:00:50 INFO - Cleaning up... 07:00:50 INFO - Return code: 0 07:00:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:00:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:00:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:00:50 INFO - Using env: {'DISPLAY': ':0', 07:00:50 INFO - 'HOME': '/home/cltbld', 07:00:50 INFO - 'LANG': 'en_US.UTF-8', 07:00:50 INFO - 'LANGUAGE': 'en_US:en', 07:00:50 INFO - 'LOGNAME': 'cltbld', 07:00:50 INFO - 'MAIL': '/var/mail/cltbld', 07:00:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:50 INFO - 'MOZ_NO_REMOTE': '1', 07:00:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:50 INFO - 'NO_EM_RESTART': '1', 07:00:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:50 INFO - 'PWD': '/builds/slave/test', 07:00:50 INFO - 'SHELL': '/bin/bash', 07:00:50 INFO - 'SHLVL': '1', 07:00:50 INFO - 'TERM': 'linux', 07:00:50 INFO - 'TMOUT': '86400', 07:00:50 INFO - 'USER': 'cltbld', 07:00:50 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:00:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:50 INFO - '_': '/tools/buildbot/bin/python'} 07:00:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:50 INFO - Downloading/unpacking psutil>=3.1.1 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00: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 07:00:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:00:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:52 INFO - Installing collected packages: psutil 07:00:52 INFO - Running setup.py install for psutil 07:00:52 INFO - building 'psutil._psutil_linux' extension 07:00:52 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 07:00:54 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 07:00:54 INFO - building 'psutil._psutil_posix' extension 07:00:54 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 07:00:54 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 07:00:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:00:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:00:54 INFO - Successfully installed psutil 07:00:54 INFO - Cleaning up... 07:00:54 INFO - Return code: 0 07:00:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:00:54 INFO - Using env: {'DISPLAY': ':0', 07:00:54 INFO - 'HOME': '/home/cltbld', 07:00:54 INFO - 'LANG': 'en_US.UTF-8', 07:00:54 INFO - 'LANGUAGE': 'en_US:en', 07:00:54 INFO - 'LOGNAME': 'cltbld', 07:00:54 INFO - 'MAIL': '/var/mail/cltbld', 07:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:54 INFO - 'MOZ_NO_REMOTE': '1', 07:00:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:54 INFO - 'NO_EM_RESTART': '1', 07:00:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:54 INFO - 'PWD': '/builds/slave/test', 07:00:54 INFO - 'SHELL': '/bin/bash', 07:00:54 INFO - 'SHLVL': '1', 07:00:54 INFO - 'TERM': 'linux', 07:00:54 INFO - 'TMOUT': '86400', 07:00:54 INFO - 'USER': 'cltbld', 07:00:54 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:54 INFO - '_': '/tools/buildbot/bin/python'} 07:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:00:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:00:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:00:56 INFO - Installing collected packages: mozsystemmonitor 07:00:56 INFO - Running setup.py install for mozsystemmonitor 07:00:57 INFO - Successfully installed mozsystemmonitor 07:00:57 INFO - Cleaning up... 07:00:57 INFO - Return code: 0 07:00:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:00:57 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')]} 07:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:57 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')]} 07:00:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:57 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:00:57 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 07:00:57 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 07:00:57 INFO - Using env: {'DISPLAY': ':0', 07:00:57 INFO - 'HOME': '/home/cltbld', 07:00:57 INFO - 'LANG': 'en_US.UTF-8', 07:00:57 INFO - 'LANGUAGE': 'en_US:en', 07:00:57 INFO - 'LOGNAME': 'cltbld', 07:00:57 INFO - 'MAIL': '/var/mail/cltbld', 07:00:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:57 INFO - 'MOZ_NO_REMOTE': '1', 07:00:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:57 INFO - 'NO_EM_RESTART': '1', 07:00:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:57 INFO - 'PWD': '/builds/slave/test', 07:00:57 INFO - 'SHELL': '/bin/bash', 07:00:57 INFO - 'SHLVL': '1', 07:00:57 INFO - 'TERM': 'linux', 07:00:57 INFO - 'TMOUT': '86400', 07:00:57 INFO - 'USER': 'cltbld', 07:00:57 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:00:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:57 INFO - '_': '/tools/buildbot/bin/python'} 07:00:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:57 INFO - Downloading/unpacking jsonschema==2.5.1 07:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:00:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:00:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:00:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:00:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 INFO - Downloading functools32-3.2.3-2.tar.gz 07:00:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:00:59 INFO - warning: no files found matching '*.txt' 07:00:59 INFO - no previously-included directories found matching 'build' 07:00:59 INFO - no previously-included directories found matching 'dist' 07:00:59 INFO - no previously-included directories found matching '.git*' 07:00:59 INFO - Installing collected packages: jsonschema, functools32 07:00:59 INFO - Running setup.py install for functools32 07:00:59 INFO - warning: no files found matching '*.txt' 07:00:59 INFO - no previously-included directories found matching 'build' 07:00:59 INFO - no previously-included directories found matching 'dist' 07:00:59 INFO - no previously-included directories found matching '.git*' 07:00:59 INFO - Successfully installed jsonschema functools32 07:00:59 INFO - Cleaning up... 07:00:59 INFO - Return code: 0 07:00:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:00:59 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')]} 07:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:59 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')]} 07:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:00:59 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:00:59 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 07:00:59 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 07:00:59 INFO - Using env: {'DISPLAY': ':0', 07:00:59 INFO - 'HOME': '/home/cltbld', 07:00:59 INFO - 'LANG': 'en_US.UTF-8', 07:00:59 INFO - 'LANGUAGE': 'en_US:en', 07:00:59 INFO - 'LOGNAME': 'cltbld', 07:00:59 INFO - 'MAIL': '/var/mail/cltbld', 07:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:00:59 INFO - 'MOZ_NO_REMOTE': '1', 07:00:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:00:59 INFO - 'NO_EM_RESTART': '1', 07:00:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:00:59 INFO - 'PWD': '/builds/slave/test', 07:00:59 INFO - 'SHELL': '/bin/bash', 07:00:59 INFO - 'SHLVL': '1', 07:00:59 INFO - 'TERM': 'linux', 07:00:59 INFO - 'TMOUT': '86400', 07:00:59 INFO - 'USER': 'cltbld', 07:00:59 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:00:59 INFO - '_': '/tools/buildbot/bin/python'} 07:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:00:59 INFO - Downloading/unpacking blobuploader==1.2.4 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 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 07:00:59 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 07:01:01 INFO - Downloading blobuploader-1.2.4.tar.gz 07:01:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:01:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:01:01 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 07:01:01 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 07:01:01 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 07:01:01 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 07:01:01 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 07:01:01 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 07:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:01:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:01:02 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 07:01:02 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 07:01:02 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 07:01:02 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 07:01:02 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 07:01:02 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 07:01:02 INFO - Downloading docopt-0.6.1.tar.gz 07:01:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:01:02 INFO - Installing collected packages: blobuploader, requests, docopt 07:01:02 INFO - Running setup.py install for blobuploader 07:01:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:01:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:01:02 INFO - Running setup.py install for requests 07:01:03 INFO - Running setup.py install for docopt 07:01:03 INFO - Successfully installed blobuploader requests docopt 07:01:03 INFO - Cleaning up... 07:01:03 INFO - Return code: 0 07:01:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:03 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')]} 07:01:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:03 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')]} 07:01:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:03 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:01:03 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 07:01:03 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 07:01:03 INFO - Using env: {'DISPLAY': ':0', 07:01:03 INFO - 'HOME': '/home/cltbld', 07:01:03 INFO - 'LANG': 'en_US.UTF-8', 07:01:03 INFO - 'LANGUAGE': 'en_US:en', 07:01:03 INFO - 'LOGNAME': 'cltbld', 07:01:03 INFO - 'MAIL': '/var/mail/cltbld', 07:01:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:03 INFO - 'MOZ_NO_REMOTE': '1', 07:01:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:03 INFO - 'NO_EM_RESTART': '1', 07:01:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:03 INFO - 'PWD': '/builds/slave/test', 07:01:03 INFO - 'SHELL': '/bin/bash', 07:01:03 INFO - 'SHLVL': '1', 07:01:03 INFO - 'TERM': 'linux', 07:01:03 INFO - 'TMOUT': '86400', 07:01:03 INFO - 'USER': 'cltbld', 07:01:03 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:01:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:03 INFO - '_': '/tools/buildbot/bin/python'} 07:01:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:01:03 INFO - Running setup.py (path:/tmp/pip-WLQTAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:01:03 INFO - Running setup.py (path:/tmp/pip-c0PhpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:01:03 INFO - Running setup.py (path:/tmp/pip-ZZXQFU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:01:03 INFO - Running setup.py (path:/tmp/pip-Nt4ceH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:01:03 INFO - Running setup.py (path:/tmp/pip-lLfJSV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:01:03 INFO - Running setup.py (path:/tmp/pip-6gfehv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:01:03 INFO - Running setup.py (path:/tmp/pip-xwrDai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:01:03 INFO - Running setup.py (path:/tmp/pip-jdFeYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:01:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:01:03 INFO - Running setup.py (path:/tmp/pip-mN6bzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:01:04 INFO - Running setup.py (path:/tmp/pip-elZ6lv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:01:04 INFO - Running setup.py (path:/tmp/pip-EGLduE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:01:04 INFO - Running setup.py (path:/tmp/pip-_z42W9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:01:04 INFO - Running setup.py (path:/tmp/pip-PWnr2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:01:04 INFO - Running setup.py (path:/tmp/pip-mZJKON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:04 INFO - Running setup.py (path:/tmp/pip-K8ATcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:01:04 INFO - Running setup.py (path:/tmp/pip-il5OXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:01:04 INFO - Running setup.py (path:/tmp/pip-J5HPc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:01:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:01:04 INFO - Running setup.py install for manifestparser 07:01:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:01:04 INFO - Running setup.py install for mozcrash 07:01:04 INFO - Running setup.py install for mozdebug 07:01:04 INFO - Running setup.py install for mozdevice 07:01:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for mozfile 07:01:05 INFO - Running setup.py install for mozhttpd 07:01:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for mozinfo 07:01:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for mozInstall 07:01:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for mozleak 07:01:05 INFO - Running setup.py install for mozlog 07:01:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for moznetwork 07:01:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for mozprocess 07:01:05 INFO - Running setup.py install for mozprofile 07:01:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:01:05 INFO - Running setup.py install for mozrunner 07:01:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:01:06 INFO - Running setup.py install for mozscreenshot 07:01:06 INFO - Running setup.py install for moztest 07:01:06 INFO - Running setup.py install for mozversion 07:01:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:01:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:01:06 INFO - Cleaning up... 07:01:06 INFO - Return code: 0 07:01:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:06 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')]} 07:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:06 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')]} 07:01:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:06 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:01:06 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 07:01:06 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 07:01:06 INFO - Using env: {'DISPLAY': ':0', 07:01:06 INFO - 'HOME': '/home/cltbld', 07:01:06 INFO - 'LANG': 'en_US.UTF-8', 07:01:06 INFO - 'LANGUAGE': 'en_US:en', 07:01:06 INFO - 'LOGNAME': 'cltbld', 07:01:06 INFO - 'MAIL': '/var/mail/cltbld', 07:01:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:06 INFO - 'MOZ_NO_REMOTE': '1', 07:01:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:06 INFO - 'NO_EM_RESTART': '1', 07:01:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:06 INFO - 'PWD': '/builds/slave/test', 07:01:06 INFO - 'SHELL': '/bin/bash', 07:01:06 INFO - 'SHLVL': '1', 07:01:06 INFO - 'TERM': 'linux', 07:01:06 INFO - 'TMOUT': '86400', 07:01:06 INFO - 'USER': 'cltbld', 07:01:06 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:01:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:06 INFO - '_': '/tools/buildbot/bin/python'} 07:01:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:01:06 INFO - Running setup.py (path:/tmp/pip-2hmOrn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:01:06 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)) 07:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:01:06 INFO - Running setup.py (path:/tmp/pip-cNFU4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:01:06 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)) 07:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:01:06 INFO - Running setup.py (path:/tmp/pip-sE4pAC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:01:06 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)) 07:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:01:06 INFO - Running setup.py (path:/tmp/pip-r3JRPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:01:06 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)) 07:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:01:06 INFO - Running setup.py (path:/tmp/pip-BDRxMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:01:07 INFO - Running setup.py (path:/tmp/pip-LADLG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:01:07 INFO - Running setup.py (path:/tmp/pip-Bqz5rA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:01:07 INFO - Running setup.py (path:/tmp/pip-qYLFEy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:01:07 INFO - Running setup.py (path:/tmp/pip-jflS8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:01:07 INFO - Running setup.py (path:/tmp/pip-OmpvsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:01:07 INFO - Running setup.py (path:/tmp/pip-EFFAzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:01:07 INFO - Running setup.py (path:/tmp/pip-5gDxou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:01:07 INFO - Running setup.py (path:/tmp/pip-rqZWtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:01:07 INFO - Running setup.py (path:/tmp/pip-G3WC_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:01:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:07 INFO - Running setup.py (path:/tmp/pip-tQGtju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:01:07 INFO - Running setup.py (path:/tmp/pip-u6hEpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:01:07 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)) 07:01:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:01:07 INFO - Running setup.py (path:/tmp/pip-MYLRIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:01:07 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)) 07:01:07 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)) 07:01:07 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)) 07:01:07 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)) 07:01:07 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)) 07:01:07 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)) 07:01:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:01:07 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 07:01:07 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 07:01:07 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 07:01:07 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 07:01:07 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 07:01:07 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 07:01:09 INFO - Downloading blessings-1.6.tar.gz 07:01:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:01:09 INFO - Installing collected packages: blessings 07:01:09 INFO - Running setup.py install for blessings 07:01:10 INFO - Successfully installed blessings 07:01:10 INFO - Cleaning up... 07:01:10 INFO - Return code: 0 07:01:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:01:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:01:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:01:10 INFO - Reading from file tmpfile_stdout 07:01:10 INFO - Current package versions: 07:01:10 INFO - argparse == 1.2.1 07:01:10 INFO - blessings == 1.6 07:01:10 INFO - blobuploader == 1.2.4 07:01:10 INFO - docopt == 0.6.1 07:01:10 INFO - functools32 == 3.2.3-2 07:01:10 INFO - jsonschema == 2.5.1 07:01:10 INFO - manifestparser == 1.1 07:01:10 INFO - mozInstall == 1.12 07:01:10 INFO - mozcrash == 0.17 07:01:10 INFO - mozdebug == 0.1 07:01:10 INFO - mozdevice == 0.48 07:01:10 INFO - mozfile == 1.2 07:01:10 INFO - mozhttpd == 0.7 07:01:10 INFO - mozinfo == 0.9 07:01:10 INFO - mozleak == 0.1 07:01:10 INFO - mozlog == 3.2 07:01:10 INFO - moznetwork == 0.27 07:01:10 INFO - mozprocess == 0.23 07:01:10 INFO - mozprofile == 0.28 07:01:10 INFO - mozrunner == 6.12 07:01:10 INFO - mozscreenshot == 0.1 07:01:10 INFO - mozsystemmonitor == 0.3 07:01:10 INFO - moztest == 0.7 07:01:10 INFO - mozversion == 1.4 07:01:10 INFO - psutil == 3.1.1 07:01:10 INFO - requests == 1.2.3 07:01:10 INFO - wsgiref == 0.1.2 07:01:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:10 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:01:10 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 07:01:10 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 07:01:10 INFO - Using env: {'DISPLAY': ':0', 07:01:10 INFO - 'HOME': '/home/cltbld', 07:01:10 INFO - 'LANG': 'en_US.UTF-8', 07:01:10 INFO - 'LANGUAGE': 'en_US:en', 07:01:10 INFO - 'LOGNAME': 'cltbld', 07:01:10 INFO - 'MAIL': '/var/mail/cltbld', 07:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:10 INFO - 'MOZ_NO_REMOTE': '1', 07:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:10 INFO - 'NO_EM_RESTART': '1', 07:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:10 INFO - 'PWD': '/builds/slave/test', 07:01:10 INFO - 'SHELL': '/bin/bash', 07:01:10 INFO - 'SHLVL': '1', 07:01:10 INFO - 'TERM': 'linux', 07:01:10 INFO - 'TMOUT': '86400', 07:01:10 INFO - 'USER': 'cltbld', 07:01:10 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:10 INFO - '_': '/tools/buildbot/bin/python'} 07:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 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)) 07:01:10 INFO - Cleaning up... 07:01:10 INFO - Return code: 0 07:01:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:10 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:01:10 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 07:01:10 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 07:01:10 INFO - Using env: {'DISPLAY': ':0', 07:01:10 INFO - 'HOME': '/home/cltbld', 07:01:10 INFO - 'LANG': 'en_US.UTF-8', 07:01:10 INFO - 'LANGUAGE': 'en_US:en', 07:01:10 INFO - 'LOGNAME': 'cltbld', 07:01:10 INFO - 'MAIL': '/var/mail/cltbld', 07:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:10 INFO - 'MOZ_NO_REMOTE': '1', 07:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:10 INFO - 'NO_EM_RESTART': '1', 07:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:10 INFO - 'PWD': '/builds/slave/test', 07:01:10 INFO - 'SHELL': '/bin/bash', 07:01:10 INFO - 'SHLVL': '1', 07:01:10 INFO - 'TERM': 'linux', 07:01:10 INFO - 'TMOUT': '86400', 07:01:10 INFO - 'USER': 'cltbld', 07:01:10 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:10 INFO - '_': '/tools/buildbot/bin/python'} 07:01:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:01:10 INFO - Cleaning up... 07:01:10 INFO - Return code: 0 07:01:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:01:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:01:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:01:10 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 0x7fcb9fd61f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x29f58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2ac0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, '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': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', '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 07:01:10 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 07:01:10 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 07:01:10 INFO - Using env: {'DISPLAY': ':0', 07:01:10 INFO - 'HOME': '/home/cltbld', 07:01:10 INFO - 'LANG': 'en_US.UTF-8', 07:01:10 INFO - 'LANGUAGE': 'en_US:en', 07:01:10 INFO - 'LOGNAME': 'cltbld', 07:01:10 INFO - 'MAIL': '/var/mail/cltbld', 07:01:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:10 INFO - 'MOZ_NO_REMOTE': '1', 07:01:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:10 INFO - 'NO_EM_RESTART': '1', 07:01:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:10 INFO - 'PWD': '/builds/slave/test', 07:01:10 INFO - 'SHELL': '/bin/bash', 07:01:10 INFO - 'SHLVL': '1', 07:01:10 INFO - 'TERM': 'linux', 07:01:10 INFO - 'TMOUT': '86400', 07:01:10 INFO - 'USER': 'cltbld', 07:01:10 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:01:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:10 INFO - '_': '/tools/buildbot/bin/python'} 07:01:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:01:11 INFO - Downloading/unpacking flake8 07:01:11 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 07:01:11 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 07:01:11 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 07:01:11 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 07:01:11 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 07:01:11 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 07:01:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:01:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:01:12 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 07:01:12 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 07:01:12 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 07:01:12 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 07:01:12 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 07:01:12 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 07:01:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:01:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 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 07:01:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:01:13 INFO - no previously-included directories found matching 'docs/_build' 07:01:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:01:13 INFO - Running setup.py install for pep8 07:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:01:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:01:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:01:13 INFO - no previously-included directories found matching 'docs/_build' 07:01:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:01:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:01:13 INFO - Cleaning up... 07:01:13 INFO - Return code: 0 07:01:13 INFO - Running post-action listener: _resource_record_post_action 07:01:13 INFO - Running post-action listener: _start_resource_monitoring 07:01:13 INFO - Starting resource monitoring. 07:01:13 INFO - ##### 07:01:13 INFO - ##### Finished create-virtualenv step (success) 07:01:13 INFO - ##### 07:01:13 INFO - ##### 07:01:13 INFO - ##### Running install step. 07:01:13 INFO - ##### 07:01:13 INFO - Running pre-action listener: _resource_record_pre_action 07:01:13 INFO - Running main action method: install 07:01:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:01:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:01:14 INFO - Reading from file tmpfile_stdout 07:01:14 INFO - Detecting whether we're running mozinstall >=1.0... 07:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:01:14 INFO - Reading from file tmpfile_stdout 07:01:14 INFO - Output received: 07:01:14 INFO - Usage: mozinstall [options] installer 07:01:14 INFO - Options: 07:01:14 INFO - -h, --help show this help message and exit 07:01:14 INFO - -d DEST, --destination=DEST 07:01:14 INFO - Directory to install application into. [default: 07:01:14 INFO - "/builds/slave/test"] 07:01:14 INFO - --app=APP Application being installed. [default: firefox] 07:01:14 INFO - mkdir: /builds/slave/test/build/application 07:01:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 07:01:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 07:01:30 INFO - Reading from file tmpfile_stdout 07:01:30 INFO - Output received: 07:01:30 INFO - /builds/slave/test/build/application/firefox/firefox 07:01:30 INFO - Running post-action listener: _resource_record_post_action 07:01:30 INFO - ##### 07:01:30 INFO - ##### Finished install step (success) 07:01:30 INFO - ##### 07:01:30 INFO - ##### 07:01:30 INFO - ##### Running run-tests step. 07:01:30 INFO - ##### 07:01:30 INFO - Running pre-action listener: _resource_record_pre_action 07:01:30 INFO - Running main action method: run_tests 07:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:01:30 INFO - Python 2.7.3 07:01:30 INFO - Return code: 0 07:01:30 INFO - grabbing minidump binary from tooltool 07:01:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:01:30 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 0x2b4f1c0>, '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 0x2b500c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2b50550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:01:30 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 07:01:30 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 07:01:30 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 07:01:30 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:01:30 INFO - Return code: 0 07:01:30 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:01:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:01:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:01:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:01:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:01:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:01:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:01:31 INFO - Return code: 0 07:01:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 07:01:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-069 --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 07:01:31 INFO - Using env: {'DISPLAY': ':0', 07:01:31 INFO - 'HOME': '/home/cltbld', 07:01:31 INFO - 'LANG': 'en_US.UTF-8', 07:01:31 INFO - 'LANGUAGE': 'en_US:en', 07:01:31 INFO - 'LOGNAME': 'cltbld', 07:01:31 INFO - 'MAIL': '/var/mail/cltbld', 07:01:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:01:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:01:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:01:31 INFO - 'MOZ_NO_REMOTE': '1', 07:01:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:01:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:01:31 INFO - 'NO_EM_RESTART': '1', 07:01:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:01:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:01:31 INFO - 'PWD': '/builds/slave/test', 07:01:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:01:31 INFO - 'SHELL': '/bin/bash', 07:01:31 INFO - 'SHLVL': '1', 07:01:31 INFO - 'TERM': 'linux', 07:01:31 INFO - 'TMOUT': '86400', 07:01:31 INFO - 'USER': 'cltbld', 07:01:31 INFO - 'XDG_SESSION_COOKIE': '05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894', 07:01:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:01:31 INFO - '_': '/tools/buildbot/bin/python'} 07:01:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-069', '--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 07:01:31 INFO - mozversion application_buildid: 20160714105442 07:01:31 INFO - mozversion application_changeset: 2299c291b4398bbb8caa79bfc6064102e053e3ff 07:01:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:01:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:01:31 INFO - mozversion application_name: Firefox 07:01:31 INFO - mozversion application_remotingname: firefox-dev 07:01:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:01:31 INFO - mozversion application_vendor: Mozilla 07:01:31 INFO - mozversion application_version: 49.0a2 07:01:31 INFO - mozversion platform_buildid: 20160714105442 07:01:31 INFO - mozversion platform_changeset: 2299c291b4398bbb8caa79bfc6064102e053e3ff 07:01:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:01:31 INFO - mozversion platform_version: 49.0a2 07:01:31 INFO - using testdate: 1468591291 07:01:31 INFO - actual date: 1468591291 07:01:31 INFO - starting webserver on 'localhost:55960' 07:01:31 INFO - SUITE-START | Running 2 tests 07:01:31 INFO - TEST-START | dromaeo_css 07:01:31 INFO - operating with platform_type : linux_ 07:01:31 INFO - Initialising browser for dromaeo_css test... 07:01:31 INFO - TEST-INFO | started process 3294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkT0EZw/profile http://localhost:55960/getInfo.html) 07:01:38 INFO - PROCESS | 3294 | __metrics Screen width/height:1600/1200 07:01:38 INFO - PROCESS | 3294 | colorDepth:24 07:01:38 INFO - PROCESS | 3294 | Browser inner width/height: 1024/697 07:01:38 INFO - PROCESS | 3294 | __metrics 07:01:40 INFO - PROCESS | 3294 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:01:40 ERROR - PROCESS | 3294 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:01:40 INFO - TEST-INFO | 3294: exit 0 07:01:40 INFO - Browser initialized. 07:01:40 INFO - Running cycle 1/1 for dromaeo_css test... 07:01:40 INFO - TEST-INFO | started process 3386 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpkT0EZw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:01:52 INFO - PROCESS | 3386 | RSS: Main: 145256448 07:01:52 INFO - PROCESS | 3386 | 07:02:43 INFO - PROCESS | 3386 | 1468591363966 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] 07:04:52 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:55960/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55960/tests/dromaeo/cssquery-ext.html) 07:04:53 INFO - PROCESS | 3386 | RSS: Main: 252366848 07:04:53 INFO - PROCESS | 3386 | 07:07:55 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:55960/tests/dromaeo/cssquery-ext.html (next: http://localhost:55960/tests/dromaeo/cssquery-jquery.html) 07:07:56 INFO - PROCESS | 3386 | RSS: Main: 234471424 07:07:56 INFO - PROCESS | 3386 | 07:10:57 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:55960/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55960/tests/dromaeo/cssquery-mootools.html) 07:10:58 INFO - PROCESS | 3386 | RSS: Main: 224768000 07:10:58 INFO - PROCESS | 3386 | 07:14:00 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:55960/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55960/tests/dromaeo/cssquery-prototype.html) 07:14:00 INFO - PROCESS | 3386 | RSS: Main: 219021312 07:14:00 INFO - PROCESS | 3386 | 07:17:03 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:55960/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55960/tests/dromaeo/cssquery-yui.html) 07:17:03 INFO - PROCESS | 3386 | RSS: Main: 217575424 07:17:03 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | Cycle 1(1): loaded http://localhost:55960/tests/dromaeo/cssquery-yui.html (next: http://localhost:55960/tests/dromaeo/cssquery-dojo.html) 07:20:06 INFO - PROCESS | 3386 | RSS: Main: 259346432 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | __start_tp_report 07:20:06 INFO - PROCESS | 3386 | _x_x_mozilla_page_load 07:20:06 INFO - PROCESS | 3386 | _x_x_mozilla_page_load_details 07:20:06 INFO - PROCESS | 3386 | |i|pagename|runs| 07:20:06 INFO - PROCESS | 3386 | |0;dojo.html;3295.5880514935634;3340.8830690925824;3342.548755917952;3352.2625022495045;3383.323335332934;5393.244945707599;5486.423925487826;5504.80733174339;5509.393966663663;5554.805581804638;5471.699056551888;5528.695921724304;5539.944600553993;5552;5574.1917421973785;4809.663323567352;4860.781264843073;4989.451160372356;5044.722540260284;5062.797488100471;4884.780184891689;4964.975175124119;4968.205087186033;4968.403791545002;4968.57767089796;4981.252812078199;5004.799808007675;5025.949740502585;5062.240663900426;5069.163588007974;5090.440051594322;5114.207297868855;5115.309433226504;5116.641835071547;5139.306193663845;4874.147024270738;4920.3357546731095;4926.704397736112;5009.223570346602;5032.295478632995;5306.602004849652;5367.919481207785;5369.275147855029;5390.973045134789;5411.377691565481;5870.941290587082;5887;5897.941020589782;5900.4984576310635;5923.496502797268;5847.122931560258;5909.2022576951995;5923.052311630118;5924.674142922137;5956.16613674086;5534.861628459321;5546.667199967975;5584.078627026536;5597.860053498695;5599.552035837123;5830.271216097988;6072.638210631912;6081.27024757032;6089.451949324582;6097.542684298606;5603;5639.63342382752;5647.463490968352;5691.117876729114;5739.426057394227;5805.8548536286935;5816.272965879265;5825.679587622637;5827.708614569255;5832.708364581754;5645.830625081254;5694.572907031989;5755.280589926259;5762.394948530344;5766.221560089418;5725.227094342294;5818.650880947157;5822.214001109796;5822.417758224144;5858.091995740667;4493.326001099861;4496.820127194941;4501.257292546693;4501.7974191161475;4524.47968483622;3954.980225098856;3977.204559088194;4017.1563971565715;4025.597440255951;4054.2094291613444;5557.888842223133;5589.273394458694;5598.804041858597;5600.551955843523;5612.466815652507;5868.09044598088;5869.8239052828485;5879.353271140171;5890.3815099414805;5905.320888097838;5417.59368047398;5465.699386533779;5505.862353441196;5507.03626865297;5580.051391263495;5024.773885175176;5036.798528058836;5038.564512671171;5051.520105589963;5056.191009438546;6040.607360521552;6041.18444010053;6052.364501727343;6058.636481811105;6069.332373438918;5631.408702086222;5644.576656750843;5746.31044274673;5757.337906140848;5863.648181109147;5968.253968253968;5978.551608629457;5991.071383935497;5992.250968628921;5992.880142397041;5734.311882574118;5739;5739;5747.224124743107;5750.8849823002465;5753.597248192586;5766.538676905753;5813.912791308218;5817.680027598353;5876.529877609695;5709.628874123168;5715.9142612860005;5720.3421606515785;5723.656580605177;5723.856903577277;5554.527865131509;5651.265335506358;5693.032184528723;5704.68624225655;5707.315122185364;5562.360328562106;5615.410381909841;5639.407862174413;5670.376258611632;5681.119426488901;5727.140928860705;5777.133429985536;5804.738786754522;5819.94180058194;5825.50483208932;5414.702191379354;5518;5643.915341269966;5664.291963504562;5668 07:20:06 INFO - PROCESS | 3386 | |1;ext.html;599.6731781179257;625.7152995387098;648.9026646003098;650.9909640057912;661.107504868428;33141.50287745686;45896.3115553267;46957.0608587828;47107.5289247107;47135;48953.51046489534;49256.2611560826;49279;49359.75320123403;49408.25887611679;8480.575971201446;9218.539073046337;9354.532273386338;9384;10886.72783180419;10669.733256668567;11056.502457389397;11150.832737508903;11152.609658661948;11154.553817847276;11166.944165279163;11189.832152517718;11219.15856310776;11219.214654974156;11222.270552414107;8412.579371031454;11066.39134847583;11140.442977851075;11341.603043893507;11410.20128590999;10757.892421075767;11175.44122793857;11181.664550063511;11190.720231994184;11199.27204731698;45757.54242457566;46265.768671156766;46363.76818115922;46385;46448.76775616134;3938.4289278054807;3967.7817720025387;4069.511658600957;4459.331100334944;4584.1977653910435;34285.82857085724;35198.29603407943;35426.46860297098;35478.8226058868;36093.81953090244;4703.435587729495;4715.646326525524;4752.857414277577;4754.66717329787;4760.476347601761;399424;399752;400164;400168.9999999971;400556;32285.838570807464;71095.28904710886;71525;72098;72210.63894680493;32215.35569288648;32276.515852262233;32348.029559113264;32359;32389.19027024343;15618.062916225063;15686.215689215494;15709.293081811345;15712.21438928049;15729.370825167094;13612.183269003892;14877.256137193097;15026.549203523911;15094.471693490676;15335.693286134216;7002.929970700329;7010.789676309617;7011.1586609607175;7034.402075823612;7063.223045464994;2796.300924768808;2799.566067259578;2815.4087641595083;2827.90102346421;2861.6852146263896;1087.342157994418;1105.2042529378834;1118.429600903545;1184.176996987103;1199.1785626845638;34079.97760067202;34353.31293374168;34587.82706086453;34611.30777384438;34673.47989780155;1182.7457096724245;1199.1426130316866;1206.4932728254155;1213.6055781870928;1238.0467040378858;18091.54771130706;18111.366102186625;18157.45527634173;18226.270949161884;18242.361517347093;161836.1908190475;162437.18781406016;162511.18744406203;163039;163179;63737.36262637407;64228.67885660542;64262;64273.357266426734;64341.678291609176;1365.481117175467;1414.4779499047297;1450.7026059657744;1476.1733429279639;1505.3301280929882;32250.838745806122;35367;35766.64233357633;35775.284494309446;36012.6591771534;55205.44794552003;60531.39468605434;61870.69064654648;61983.690081549306;66920.33079669336;486265;491080;491119;491472;495177;36228.81885590555;41618.375724363555;47680.7615961918;47979.88130771489;48321;48390.75804620954;53142;53165;53409;53917.19124213218;44076;44294.476092177814;44630.3305450412;44777.10445791131;45539.316910247035;42595.787021064694;43097.353539696305;43870;43951.12097757963;44291.11417771562;44257.05002604206;44577.55422445864;44848.32727509025;45033;45370.77314613406;38534;39699.40450893181;39902.201955961296;40364;40387.3941890866;29466;29510.852445737633;29862;30021.849890750407;30133.698663013965 07:20:06 INFO - PROCESS | 3386 | |2;jquery.html;428.42163079842214;430.4060396652619;437.73079556073014;438.3274296004592;439.89222640453073;19007.809921900778;20084.497887552818;20723;20815.16739330425;21066.157353705872;19780.802191978113;20128.396148115542;20297.797022029776;20312;20366.389008329737;5501.0648189807725;5744.712764361765;5779.393163717812;5782.971085144568;5784;5645.2096706461125;5646.18130371096;5755.568332375088;5766.798162064329;5779.21980532629;5729.914051289234;5730.111832665943;5732.111522713986;5736.541076713874;5737.512311453532;5598.384177740445;5711.657300561958;5888.29340479141;5929.733162007698;6002.8499287517725;5616;5815.360310365856;5822.388649191858;5825.213596164506;5830.621009634403;14181.716365672732;14415.78376324356;14416.711665766627;14425.711485770331;16772.916135419244;2348.1077190667606;2455.5702752018324;2494.7006359237007;2761.765249953756;2783.6102945587636;10730.570777168903;11097.056750176163;11181.161412893984;12130;13043.152195107288;1877.1271358818215;1955.4329244519072;1960.8725432846961;2010.5275260313815;2056.9715142428786;79715.20284797136;81786.59106704488;83557.58221208856;86107.56946215354;87046.56476717639;11877.524899004116;11904.880951190551;11909.225900316625;11993.100517461226;12064.698382540506;16897.662046758996;17003.574910627332;17010.82989170117;17044.48866534006;17051.658966820592;8955.373123881265;9070.319726020574;9107.499087550248;9134.588943497558;9341.813163736822;8935.910640893639;8976.237019853386;8976.8653470198;9072.591733372014;9134;5073.213651883964;5073.568746656501;5104.336436263262;5104.515071068243;5196.662216955886;1985.007496251874;1987.6124155789482;2036.75558932929;2047.8976051197678;2049.723287356188;993.9006099390003;1005.7334806276342;1006.0090810551601;1076.2788931415814;1112.7496313329436;19667.803321966596;19886.801131988494;20499.8975005124;20574.177032918516;20574.382768516967;1039.3971496531992;1063.6596289187385;1064.292245656645;1070.0797314309686;1101.730076131353;11235.83146252807;11403.600873969372;11420.200585958903;11519.539218431337;11525.942370288263;486875;491534;500288;512612;515757;53629.731851341276;72037.6398118006;94075;95744;95817.52091239499;1050.9789804203874;1057.6245432871374;1070.7002039428817;1072.5495291977388;1078.9298695584605;17534.473965781046;17735.64528709393;17816.821831781515;17902;18179.18193681314;39245;39766.40350394689;40625;41683.37474937939;41788;58090.709546451995;58673.413265868505;59451.40548594459;60241.69879150576;61320.69339653273;8704.204818916183;8821.103462755282;8849.734507964771;8926.152015558511;9025.684101056431;12254.448549815457;13057.477700892237;13112.409941552462;13159.407826648016;13431.462741490232;6341.460975817107;10361.689149325532;11374.43127843621;11809.763804723685;11879.643610691694;11457.312561246352;11493.425328733698;11522.942385288019;11563.768724625628;11602.07183425341;11209.71975700614;11935.462904169506;12022.75954480923;12079.154459188125;12108.394580271128;8714.302855771397;8853.114688531095;8880.97352376503;9024.323175761712;9196.26429885621;11254.324740515594;11846.940765296464;11859.407029648655;11870.228435152034;12308.076894232789 07:20:06 INFO - PROCESS | 3386 | |3;mootools.html;1377.9655508612277;1501.0393348257114;1558.2987655555;1723.4915699868538;1866.6733321668705;8726.171013753692;9098.636054557817;9106.225970792473;9296.674616388427;9303.348765586412;9088.363814532986;9266;9269.397489163199;9278.304127190453;9289.396189247695;2152.4080877758665;2401.7598240176017;2420.8063354931564;2431.0397393029743;2503.812214083942;2447.4615584571457;2490;2491.9377015574664;2501.774840264376;2503.6494890715317;2440.6827112475444;2468.5803413419762;2470.8023358131304;2473.480569080495;2477.1453848422357;2127.585120901425;2351.1535847095033;2426.065964603623;2427.635854621803;2442.7679370459778;2381.57131716291;2387.307680772574;2387.9612368619705;2408.4821763320797;2437.488127493211;9569.712908612753;9606.615735370577;9609.99095095012;9625.229981601455;9625.71122866315;5452.91820622691;5599.7480113394995;5601.187827764992;5606.551475881961;5607.691576963265;3365.96634033659;3441.6042155152227;3490.912727181815;3521.049316684481;3522.5420695309704;1120.8318752187156;1125.898669119775;1126.9098472122212;1128.2328016948472;1171.168470386027;13742.175469471764;13817.723645527134;13820.101693389897;13858.307084645829;13862.514811981533;4909.189983652657;4947.381577302765;4958.9504104959215;4972.602191824645;5061.7975280989085;11479.483423245807;11538.423078846025;11549.01833344175;11558.364289963965;11565.710857228469;4964.726940018261;5644.209810626516;5650.378458369576;5658.886822263532;5664;4620.745858977722;4622.168009758276;4623.052274283764;5005.124103281911;5229.790808367623;4166.166766646683;4263.893402664959;4334.219840428753;4381;4386.605205531518;1258.2324781883044;1323.503686117706;1325.436689406998;1334.8398192216803;1338.0967846703436;1007.9395236285847;1016.9288149829441;1025.789713108826;1035.7048241251357;1052.095198129608;6204.441600255998;6229.3770622937345;6231.096491008779;6285.2457705075685;6329.050642403677;475.38437723148604;478.7917255993654;479.5635971266201;486.5864015586723;501.4033300372545;4363.127374525045;4397.73613583186;4410.33844923264;4420.425344705232;4451.910961780746;11651.359175245274;11963.282203067844;12013.579524716593;12020.038396928225;12055.095867809949;11759.176857619885;11831.230969986924;11831.940840295743;11877.940610297066;11898.46456909441;8499.405041647025;8507.70223042203;8818.11818818113;8820.779480513038;9043.18611324984;6481.833270011443;6746.69639866217;6826.385625293548;6869.862602748017;6916.20463646687;10584.153267738717;10937.507812148313;11028.117750580095;11059.612913547988;11137.888621114009;11036;11287.830682539932;11299.943500282447;11313.264637798517;11456.427178640868;8528.360372972176;8696.086910874266;8741.694040708544;8839;8840.690575829814;9370.765730856783;9379.906200938176;9396.107369799858;9419.576119074794;9467.573959171716;8557.657693692183;8586;8633.481991080555;8675.522846243264;8742.781430464289;8072.112067672669;8157.143499932422;8287.295579875778;8291.212334828182;8559.058503564727;7767.495112817649;7861.8034549134445;7862.685492580462;7868.527888326718;7869.567173805495;5462.562994960314;5593.608447408642;5648.209250704823;5669.971650141723;5755.395683453345;5357.437469065692;5393.83818485446;5495.450454954473;5508.531774799027;5597.132444471114 07:20:06 INFO - PROCESS | 3386 | |4;prototype.html;343.73704116350996;359.8524604911985;361.94027985382405;362.7424528584705;363.521968611276;5200.815657423882;5336.332958380202;5398.325209348832;5431.809886653968;5461.535769459596;5369.40936496986;5373;5383.246345511631;5400.054990376678;5424.891502169954;4281.422008028923;4303.526612072686;4313.935290970638;4324.416203812477;4342.435483387155;4058.492688413948;4165.520965089023;4280.4221430106845;4322.589354011364;4345.956540434602;4235.894102647428;4236.661067114624;4268.893277668052;4271.739836748166;4283.721558098714;4238.851640192594;4243.533211346749;4251.87244382669;4256.659467242614;4276.764777937212;3679.981600092009;3767.717421193394;3831.233753249361;3834.501514803058;3857.864974725871;5073.771680274396;5081.822136225214;5096.974515127437;5116.590672746172;5142.125838607409;4878.878028049292;4884.926726099111;4901.411830580318;4901.779419926112;4913.189323761575;5371.785128594852;5642.746076426571;5653.275750895975;5670.53331800668;5676.914846277309;4048.1498885234137;4072.1652061327654;4072.3687828386646;4074.8166332515107;4076.6331030207125;5825.388334224887;5862.237909071836;5863;5878.382769809194;5897.64614123154;3969.920601587952;4160.895977600584;4183.91632167355;4184.790760462026;4207.116505533811;4181.832726690959;4193.972476743217;4195.895102622398;4201.432806571073;4233.70364074518;4768.785404656799;4776.8566942991765;4784.760761961888;4788.425388953278;4794.376731025014;5558.388577256498;5628.521575666033;5630.74661640219;5670.291213598301;5677.489025987598;1399.237415608496;1441.891858110646;1462.992685036568;1463.8023866778062;1475.1075599262292;925.7500474871747;941.110650435332;943.9150476457014;946.966856160031;953.3803028031834;4907.607391408679;4924.581410580069;4950.727709975986;4960.330355402047;4972.577330926912;5129.692218466904;5155.74221288934;5164.321595481409;5164.896702066013;5210.6873587584105;4716.764161791897;4843.39457567804;4880.341153944243;4909.69892978361;5006.724630145304;2722.850243236641;2788.916332510028;2796.979102627526;2800.8376523742418;2802.9299267518477;5246.580273578104;5280.339957505312;5289.3917199521775;5318;5329.173978033411;4777.020710754287;5129.794808207705;5135.281060651511;5192.767110142675;5243.5542978847225;5242.61070816234;5278.419373868871;5309.8938021239355;5316.308879845596;5318.601104917069;5146.511081447256;5161.922571161361;5171.896562068813;5173.697704521603;5187.92218116721;5066.82266120684;5318.494742999409;5816.912746308724;5823.563232757476;5826.329972053268;6015.518758499222;6030.939690603213;6056.606320589148;6093.48205402528;6098.237720284965;4191.497020357577;4239.872803815891;4259.765712885697;4267.3812297217355;4272.764997925144;5617.303694226875;5665.065264231355;5813.069908814569;5824.184614153937;5856.824295271149;5633.211350410864;5656.434356564475;5665.49010589049;5673.631213971079;5677.290338707661;5632.464915832989;5680.744366503434;5683.971580142238;5686.09022556389;5689.630174038674;5415.59383046281;5550.390386787948;5569.33168019824;5584.213051823377;5598.880223955144;5612;5669.262995810518;5673.5461163107675;5686.663634046078;5687.20379146928;5342.8931421372135;5517;5544.972275138599;5560.388357280615;5576.972115139399 07:20:06 INFO - PROCESS | 3386 | |5;yui.html;782.2021538031208;786.909505406878;803.8432505661391;853.0701535326493;881.3918396306548;15065.698686026286;16058.357665693373;17402.999999999985;17416;17449.47651570452;15915.204239788021;15920.681586368266;15995.520134395958;16010.119443430607;16077.11575863327;719.3418022509397;734.4308161175105;735.1949615171379;735.9116905971291;840.0339609449152;866.6620018192879;873.2184694698241;874.2961915657893;874.7856775090112;875.3128793896792;901.4050726520497;907.5825120444604;907.6505545365048;908.8954770201412;909.4134283387211;742.1428250370831;879.766861781622;887.6227603268585;892.9508877011762;894.3694695239873;665.8801415745164;666.3070406776994;667.0994157886862;667.1760375935716;667.5260564998856;15078.773818392732;15106.320215590215;15114.168720720356;15133;15153.469628562947;14680.853191468163;15145.924270378688;15264.847351526454;15295.464658737003;15356.616084597863;13640.113392629448;13713.108647937852;13853.445862165601;14021.158730476203;14453.494127705584;4759.785809638574;4769.976150119227;4810.927836082462;4814.422269327668;4974.0797952378925;76412;76597;76674;76729;77157.22842771557;11133.387663678413;11907.821382679267;12085.335306558049;12102.33437160947;12185.573504927284;24205.63691544628;24387.14644987417;24518.632220516705;24531;24604.384890377525;9131.954340228256;9137.08629137081;9155.587998540082;9168.908310916806;9214.907850921405;4293.291606884892;4904.4605093439695;5049.848504544796;5057.848264552069;5060.503868280118;3727.4408838674417;3877.515310586177;3879.127196380848;3884.378499440076;3886.591907849635;537.9784808607612;643.2152773616181;649.0815496073099;649.5518092516148;651.205529254309;632.7009583414789;665.8768127896354;687.8555503344354;701.6071000239882;809.995950020258;9724.664898003763;9749;9961.55173017216;10105.646302379382;10113.089821916063;707.3245050976305;735.0664655886993;762.2910693055401;806.4838503357855;836.9037560680478;4635.3742244797195;4693.718376897329;4715.976420117878;4818.445878723922;4845.079434907357;78398.6080069596;125090;125098.37450812869;127339;130080;107574;107752;108944;110692.44653776841;112049.43975280071;60747;62882.68558657269;68941.65529172322;92602.99999999865;94867.52566237125;9950.502474876372;9997;10010;10011.349262297927;10329.225308102074;50125;51870.22194667158;55880;56246.87506249934;56909.715451422475;73319.63340183265;81547.18452815396;83004;83417.58291208505;84347;12612.87387126117;12658.620241392773;12741.235525868478;13105.934470327587;13209.207447553177;13980.44078236859;14123.929380353033;14349.35427905726;14387.856121438652;14417.207053612152;13256;13329.133606315558;13486.190828550318;13782.517611883535;13857.930710346787;13295.933520332337;13620.727585448434;13693.726125477235;13710.588682339545;14111.929440352733;13090.607281781562;13184.670383240496;13422.134191631592;13553.796693049415;13824.516141934984;10927.453627318444;10979.945100274448;11019.228653994143;11026.117910567296;11484.253523520945;9922.077145121888;10435.582576696846;11074.39090850011;11321.433928303717;11421.657350279505 07:20:06 INFO - PROCESS | 3386 | __end_tp_report 07:20:06 INFO - PROCESS | 3386 | __start_cc_report 07:20:06 INFO - PROCESS | 3386 | _x_x_mozilla_cycle_collect,874 07:20:06 INFO - PROCESS | 3386 | __end_cc_report 07:20:06 INFO - PROCESS | 3386 | __startTimestamp1468592406661__endTimestamp 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | ------- Summary: start ------- 07:20:06 INFO - PROCESS | 3386 | Number of tests: 6 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | [#0] dojo.html Cycles:165 Average:5436.13 Median:5639.41 stddev:586.71 (10.4%) stddev-sans-first:563.97 07:20:06 INFO - PROCESS | 3386 | Values: 3295.6 3340.9 3342.5 3352.3 3383.3 5393.2 5486.4 5504.8 5509.4 5554.8 5471.7 5528.7 5539.9 5552.0 5574.2 4809.7 4860.8 4989.5 5044.7 5062.8 4884.8 4965.0 4968.2 4968.4 4968.6 4981.3 5004.8 5025.9 5062.2 5069.2 5090.4 5114.2 5115.3 5116.6 5139.3 4874.1 4920.3 4926.7 5009.2 5032.3 5306.6 5367.9 5369.3 5391.0 5411.4 5870.9 5887.0 5897.9 5900.5 5923.5 5847.1 5909.2 5923.1 5924.7 5956.2 5534.9 5546.7 5584.1 5597.9 5599.6 5830.3 6072.6 6081.3 6089.5 6097.5 5603.0 5639.6 5647.5 5691.1 5739.4 5805.9 5816.3 5825.7 5827.7 5832.7 5645.8 5694.6 5755.3 5762.4 5766.2 5725.2 5818.7 5822.2 5822.4 5858.1 4493.3 4496.8 4501.3 4501.8 4524.5 3955.0 3977.2 4017.2 4025.6 4054.2 5557.9 5589.3 5598.8 5600.6 5612.5 5868.1 5869.8 5879.4 5890.4 5905.3 5417.6 5465.7 5505.9 5507.0 5580.1 5024.8 5036.8 5038.6 5051.5 5056.2 6040.6 6041.2 6052.4 6058.6 6069.3 5631.4 5644.6 5746.3 5757.3 5863.6 5968.3 5978.6 5991.1 5992.3 5992.9 5734.3 5739.0 5739.0 5747.2 5750.9 5753.6 5766.5 5813.9 5817.7 5876.5 5709.6 5715.9 5720.3 5723.7 5723.9 5554.5 5651.3 5693.0 5704.7 5707.3 5562.4 5615.4 5639.4 5670.4 5681.1 5727.1 5777.1 5804.7 5819.9 5825.5 5414.7 5518.0 5643.9 5664.3 5668.0 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | [#1] ext.html Cycles:180 Average:54009.19 Median:21833.59 stddev:100361.63 (459.7%) stddev-sans-first:100563.06 07:20:06 INFO - PROCESS | 3386 | Values: 599.7 625.7 648.9 651.0 661.1 33141.5 45896.3 46957.1 47107.5 47135.0 48953.5 49256.3 49279.0 49359.8 49408.3 8480.6 9218.5 9354.5 9384.0 10886.7 10669.7 11056.5 11150.8 11152.6 11154.6 11166.9 11189.8 11219.2 11219.2 11222.3 8412.6 11066.4 11140.4 11341.6 11410.2 10757.9 11175.4 11181.7 11190.7 11199.3 45757.5 46265.8 46363.8 46385.0 46448.8 3938.4 3967.8 4069.5 4459.3 4584.2 34285.8 35198.3 35426.5 35478.8 36093.8 4703.4 4715.6 4752.9 4754.7 4760.5 399424.0 399752.0 400164.0 400169.0 400556.0 32285.8 71095.3 71525.0 72098.0 72210.6 32215.4 32276.5 32348.0 32359.0 32389.2 15618.1 15686.2 15709.3 15712.2 15729.4 13612.2 14877.3 15026.5 15094.5 15335.7 7002.9 7010.8 7011.2 7034.4 7063.2 2796.3 2799.6 2815.4 2827.9 2861.7 1087.3 1105.2 1118.4 1184.2 1199.2 34080.0 34353.3 34587.8 34611.3 34673.5 1182.7 1199.1 1206.5 1213.6 1238.0 18091.5 18111.4 18157.5 18226.3 18242.4 161836.2 162437.2 162511.2 163039.0 163179.0 63737.4 64228.7 64262.0 64273.4 64341.7 1365.5 1414.5 1450.7 1476.2 1505.3 32250.8 35367.0 35766.6 35775.3 36012.7 55205.4 60531.4 61870.7 61983.7 66920.3 486265.0 491080.0 491119.0 491472.0 495177.0 36228.8 41618.4 47680.8 47979.9 48321.0 48390.8 53142.0 53165.0 53409.0 53917.2 44076.0 44294.5 44630.3 44777.1 45539.3 42595.8 43097.4 43870.0 43951.1 44291.1 44257.1 44577.6 44848.3 45033.0 45370.8 38534.0 39699.4 39902.2 40364.0 40387.4 29466.0 29510.9 29862.0 30021.8 30133.7 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | [#2] jquery.html Cycles:180 Average:29208.85 Median:20769.08 stddev:82520.00 (397.3%) stddev-sans-first:82723.20 07:20:06 INFO - PROCESS | 3386 | Values: 428.4 430.4 437.7 438.3 439.9 19007.8 20084.5 20723.0 20815.2 21066.2 19780.8 20128.4 20297.8 20312.0 20366.4 5501.1 5744.7 5779.4 5783.0 5784.0 5645.2 5646.2 5755.6 5766.8 5779.2 5729.9 5730.1 5732.1 5736.5 5737.5 5598.4 5711.7 5888.3 5929.7 6002.8 5616.0 5815.4 5822.4 5825.2 5830.6 14181.7 14415.8 14416.7 14425.7 16772.9 2348.1 2455.6 2494.7 2761.8 2783.6 10730.6 11097.1 11181.2 12130.0 13043.2 1877.1 1955.4 1960.9 2010.5 2057.0 79715.2 81786.6 83557.6 86107.6 87046.6 11877.5 11904.9 11909.2 11993.1 12064.7 16897.7 17003.6 17010.8 17044.5 17051.7 8955.4 9070.3 9107.5 9134.6 9341.8 8935.9 8976.2 8976.9 9072.6 9134.0 5073.2 5073.6 5104.3 5104.5 5196.7 1985.0 1987.6 2036.8 2047.9 2049.7 993.9 1005.7 1006.0 1076.3 1112.7 19667.8 19886.8 20499.9 20574.2 20574.4 1039.4 1063.7 1064.3 1070.1 1101.7 11235.8 11403.6 11420.2 11519.5 11525.9 486875.0 491534.0 500288.0 512612.0 515757.0 53629.7 72037.6 94075.0 95744.0 95817.5 1051.0 1057.6 1070.7 1072.5 1078.9 17534.5 17735.6 17816.8 17902.0 18179.2 39245.0 39766.4 40625.0 41683.4 41788.0 58090.7 58673.4 59451.4 60241.7 61320.7 8704.2 8821.1 8849.7 8926.2 9025.7 12254.4 13057.5 13112.4 13159.4 13431.5 6341.5 10361.7 11374.4 11809.8 11879.6 11457.3 11493.4 11522.9 11563.8 11602.1 11209.7 11935.5 12022.8 12079.2 12108.4 8714.3 8853.1 8881.0 9024.3 9196.3 11254.3 11846.9 11859.4 11870.2 12308.1 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | [#3] mootools.html Cycles:180 Average:6272.06 Median:4621.46 stddev:3686.86 (79.8%) stddev-sans-first:3678.86 07:20:06 INFO - PROCESS | 3386 | Values: 1378.0 1501.0 1558.3 1723.5 1866.7 8726.2 9098.6 9106.2 9296.7 9303.3 9088.4 9266.0 9269.4 9278.3 9289.4 2152.4 2401.8 2420.8 2431.0 2503.8 2447.5 2490.0 2491.9 2501.8 2503.6 2440.7 2468.6 2470.8 2473.5 2477.1 2127.6 2351.2 2426.1 2427.6 2442.8 2381.6 2387.3 2388.0 2408.5 2437.5 9569.7 9606.6 9610.0 9625.2 9625.7 5452.9 5599.7 5601.2 5606.6 5607.7 3366.0 3441.6 3490.9 3521.0 3522.5 1120.8 1125.9 1126.9 1128.2 1171.2 13742.2 13817.7 13820.1 13858.3 13862.5 4909.2 4947.4 4959.0 4972.6 5061.8 11479.5 11538.4 11549.0 11558.4 11565.7 4964.7 5644.2 5650.4 5658.9 5664.0 4620.7 4622.2 4623.1 5005.1 5229.8 4166.2 4263.9 4334.2 4381.0 4386.6 1258.2 1323.5 1325.4 1334.8 1338.1 1007.9 1016.9 1025.8 1035.7 1052.1 6204.4 6229.4 6231.1 6285.2 6329.1 475.4 478.8 479.6 486.6 501.4 4363.1 4397.7 4410.3 4420.4 4451.9 11651.4 11963.3 12013.6 12020.0 12055.1 11759.2 11831.2 11831.9 11877.9 11898.5 8499.4 8507.7 8818.1 8820.8 9043.2 6481.8 6746.7 6826.4 6869.9 6916.2 10584.2 10937.5 11028.1 11059.6 11137.9 11036.0 11287.8 11299.9 11313.3 11456.4 8528.4 8696.1 8741.7 8839.0 8840.7 9370.8 9379.9 9396.1 9419.6 9467.6 8557.7 8586.0 8633.5 8675.5 8742.8 8072.1 8157.1 8287.3 8291.2 8559.1 7767.5 7861.8 7862.7 7868.5 7869.6 5462.6 5593.6 5648.2 5670.0 5755.4 5357.4 5393.8 5495.5 5508.5 5597.1 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | [#4] prototype.html Cycles:180 Average:4637.52 Median:5151.13 stddev:1331.66 (25.9%) stddev-sans-first:1295.81 07:20:06 INFO - PROCESS | 3386 | Values: 343.7 359.9 361.9 362.7 363.5 5200.8 5336.3 5398.3 5431.8 5461.5 5369.4 5373.0 5383.2 5400.1 5424.9 4281.4 4303.5 4313.9 4324.4 4342.4 4058.5 4165.5 4280.4 4322.6 4346.0 4235.9 4236.7 4268.9 4271.7 4283.7 4238.9 4243.5 4251.9 4256.7 4276.8 3680.0 3767.7 3831.2 3834.5 3857.9 5073.8 5081.8 5097.0 5116.6 5142.1 4878.9 4884.9 4901.4 4901.8 4913.2 5371.8 5642.7 5653.3 5670.5 5676.9 4048.1 4072.2 4072.4 4074.8 4076.6 5825.4 5862.2 5863.0 5878.4 5897.6 3969.9 4160.9 4183.9 4184.8 4207.1 4181.8 4194.0 4195.9 4201.4 4233.7 4768.8 4776.9 4784.8 4788.4 4794.4 5558.4 5628.5 5630.7 5670.3 5677.5 1399.2 1441.9 1463.0 1463.8 1475.1 925.8 941.1 943.9 947.0 953.4 4907.6 4924.6 4950.7 4960.3 4972.6 5129.7 5155.7 5164.3 5164.9 5210.7 4716.8 4843.4 4880.3 4909.7 5006.7 2722.9 2788.9 2797.0 2800.8 2802.9 5246.6 5280.3 5289.4 5318.0 5329.2 4777.0 5129.8 5135.3 5192.8 5243.6 5242.6 5278.4 5309.9 5316.3 5318.6 5146.5 5161.9 5171.9 5173.7 5187.9 5066.8 5318.5 5816.9 5823.6 5826.3 6015.5 6030.9 6056.6 6093.5 6098.2 4191.5 4239.9 4259.8 4267.4 4272.8 5617.3 5665.1 5813.1 5824.2 5856.8 5633.2 5656.4 5665.5 5673.6 5677.3 5632.5 5680.7 5684.0 5686.1 5689.6 5415.6 5550.4 5569.3 5584.2 5598.9 5612.0 5669.3 5673.5 5686.7 5687.2 5342.9 5517.0 5545.0 5560.4 5577.0 07:20:06 INFO - PROCESS | 3386 | 07:20:06 INFO - PROCESS | 3386 | [#5] yui.html Cycles:180 Average:21443.32 Median:4704.85 stddev:31006.12 (659.0%) stddev-sans-first:31054.29 07:20:06 INFO - PROCESS | 3386 | Values: 782.2 786.9 803.8 853.1 881.4 15065.7 16058.4 17403.0 17416.0 17449.5 15915.2 15920.7 15995.5 16010.1 16077.1 719.3 734.4 735.2 735.9 840.0 866.7 873.2 874.3 874.8 875.3 901.4 907.6 907.7 908.9 909.4 742.1 879.8 887.6 893.0 894.4 665.9 666.3 667.1 667.2 667.5 15078.8 15106.3 15114.2 15133.0 15153.5 14680.9 15145.9 15264.8 15295.5 15356.6 13640.1 13713.1 13853.4 14021.2 14453.5 4759.8 4770.0 4810.9 4814.4 4974.1 76412.0 76597.0 76674.0 76729.0 77157.2 11133.4 11907.8 12085.3 12102.3 12185.6 24205.6 24387.1 24518.6 24531.0 24604.4 9132.0 9137.1 9155.6 9168.9 9214.9 4293.3 4904.5 5049.8 5057.8 5060.5 3727.4 3877.5 3879.1 3884.4 3886.6 538.0 643.2 649.1 649.6 651.2 632.7 665.9 687.9 701.6 810.0 9724.7 9749.0 9961.6 10105.6 10113.1 707.3 735.1 762.3 806.5 836.9 4635.4 4693.7 4716.0 4818.4 4845.1 78398.6 125090.0 125098.4 127339.0 130080.0 107574.0 107752.0 108944.0 110692.4 112049.4 60747.0 62882.7 68941.7 92603.0 94867.5 9950.5 9997.0 10010.0 10011.3 10329.2 50125.0 51870.2 55880.0 56246.9 56909.7 73319.6 81547.2 83004.0 83417.6 84347.0 12612.9 12658.6 12741.2 13105.9 13209.2 13980.4 14123.9 14349.4 14387.9 14417.2 13256.0 13329.1 13486.2 13782.5 13857.9 13295.9 13620.7 13693.7 13710.6 14111.9 13090.6 13184.7 13422.1 13553.8 13824.5 10927.5 10979.9 11019.2 11026.1 11484.3 9922.1 10435.6 11074.4 11321.4 11421.7 07:20:06 INFO - PROCESS | 3386 | -------- Summary: end -------- 07:20:06 INFO - PROCESS | 3386 | 07:20:07 INFO - TEST-INFO | 3386: exit 0 07:20:07 INFO - TEST-OK | dromaeo_css | took 1115946ms 07:20:07 INFO - TEST-START | kraken 07:20:07 INFO - operating with platform_type : linux_ 07:20:07 INFO - Initialising browser for kraken test... 07:20:07 INFO - TEST-INFO | started process 3578 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps07zoG/profile http://localhost:55960/getInfo.html) 07:20:11 INFO - PROCESS | 3578 | __metrics Screen width/height:1600/1200 07:20:11 INFO - PROCESS | 3578 | colorDepth:24 07:20:11 INFO - PROCESS | 3578 | Browser inner width/height: 1024/697 07:20:11 INFO - PROCESS | 3578 | __metrics 07:20:12 INFO - PROCESS | 3578 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:20:12 ERROR - PROCESS | 3578 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:20:13 INFO - TEST-INFO | 3578: exit 0 07:20:13 INFO - Browser initialized. 07:20:13 INFO - Running cycle 1/1 for kraken test... 07:20:13 INFO - TEST-INFO | started process 3667 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmps07zoG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:20:25 INFO - PROCESS | 3667 | RSS: Main: 145518592 07:20:25 INFO - PROCESS | 3667 | 07:20:27 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55960/tests/kraken/driver.html?testName=audio-beat-detection) 07:20:28 INFO - PROCESS | 3667 | RSS: Main: 194973696 07:20:28 INFO - PROCESS | 3667 | 07:20:31 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55960/tests/kraken/driver.html?testName=audio-dft) 07:20:31 INFO - PROCESS | 3667 | RSS: Main: 187715584 07:20:31 INFO - PROCESS | 3667 | 07:20:34 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55960/tests/kraken/driver.html?testName=audio-fft) 07:20:34 INFO - PROCESS | 3667 | RSS: Main: 279375872 07:20:34 INFO - PROCESS | 3667 | 07:20:37 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55960/tests/kraken/driver.html?testName=audio-oscillator) 07:20:37 INFO - PROCESS | 3667 | RSS: Main: 187658240 07:20:37 INFO - PROCESS | 3667 | 07:20:39 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55960/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:20:40 INFO - PROCESS | 3667 | RSS: Main: 188231680 07:20:40 INFO - PROCESS | 3667 | 07:20:43 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55960/tests/kraken/driver.html?testName=imaging-darkroom) 07:20:43 INFO - PROCESS | 3667 | RSS: Main: 222863360 07:20:43 INFO - PROCESS | 3667 | 07:20:47 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55960/tests/kraken/driver.html?testName=imaging-desaturate) 07:20:47 INFO - PROCESS | 3667 | RSS: Main: 222789632 07:20:47 INFO - PROCESS | 3667 | 07:20:50 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55960/tests/kraken/driver.html?testName=json-parse-financial) 07:20:51 INFO - PROCESS | 3667 | RSS: Main: 222429184 07:20:51 INFO - PROCESS | 3667 | 07:20:52 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55960/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:20:53 INFO - PROCESS | 3667 | RSS: Main: 200601600 07:20:53 INFO - PROCESS | 3667 | 07:20:55 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:20:55 INFO - PROCESS | 3667 | RSS: Main: 202588160 07:20:55 INFO - PROCESS | 3667 | 07:20:57 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:20:58 INFO - PROCESS | 3667 | RSS: Main: 207802368 07:20:58 INFO - PROCESS | 3667 | 07:21:00 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:21:01 INFO - PROCESS | 3667 | RSS: Main: 212668416 07:21:01 INFO - PROCESS | 3667 | 07:21:04 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:21:04 INFO - PROCESS | 3667 | RSS: Main: 212090880 07:21:04 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | Cycle 1(1): loaded http://localhost:55960/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55960/tests/kraken/driver.html?testName=ai-astar) 07:21:06 INFO - PROCESS | 3667 | RSS: Main: 254959616 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | __start_tp_report 07:21:06 INFO - PROCESS | 3667 | _x_x_mozilla_page_load 07:21:06 INFO - PROCESS | 3667 | _x_x_mozilla_page_load_details 07:21:06 INFO - PROCESS | 3667 | |i|pagename|runs| 07:21:06 INFO - PROCESS | 3667 | |0;ai-astar;113;106;112;110;109;111;111;112;109;110 07:21:06 INFO - PROCESS | 3667 | |1;audio-beat-detection;157;131;126;166;128;125;127;155;165;125 07:21:06 INFO - PROCESS | 3667 | |2;audio-dft;161;160;159;159;161;162;160;158;159;158 07:21:06 INFO - PROCESS | 3667 | |3;audio-fft;75;75;75;124;74;76;74;73;105;75 07:21:06 INFO - PROCESS | 3667 | |4;audio-oscillator;84;84;118;84;84;84;115;83;85;131 07:21:06 INFO - PROCESS | 3667 | |5;imaging-gaussian-blur;117;118;117;117;117;118;118;116;117;117 07:21:06 INFO - PROCESS | 3667 | |6;imaging-darkroom;146;146;145;146;146;151;146;145;145;146 07:21:06 INFO - PROCESS | 3667 | |7;imaging-desaturate;95;95;94;95;94;95;95;95;95;95 07:21:06 INFO - PROCESS | 3667 | |8;json-parse-financial;50;53;51;54;51;54;53;51;51;51 07:21:06 INFO - PROCESS | 3667 | |9;json-stringify-tinderbox;60;61;61;61;60;61;60;60;61;60 07:21:06 INFO - PROCESS | 3667 | |10;stanford-crypto-aes;75;75;75;75;76;77;77;76;111;77 07:21:06 INFO - PROCESS | 3667 | |11;stanford-crypto-ccm;131;115;124;124;122;116;125;119;122;120 07:21:06 INFO - PROCESS | 3667 | |12;stanford-crypto-pbkdf2;158;155;161;156;155;155;157;155;157;154 07:21:06 INFO - PROCESS | 3667 | |13;stanford-crypto-sha256-iterative;62;61;61;60;61;62;60;60;62;63 07:21:06 INFO - PROCESS | 3667 | __end_tp_report 07:21:06 INFO - PROCESS | 3667 | __start_cc_report 07:21:06 INFO - PROCESS | 3667 | _x_x_mozilla_cycle_collect,1591 07:21:06 INFO - PROCESS | 3667 | __end_cc_report 07:21:06 INFO - PROCESS | 3667 | __startTimestamp1468592466649__endTimestamp 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | ------- Summary: start ------- 07:21:06 INFO - PROCESS | 3667 | Number of tests: 14 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#0] ai-astar Cycles:10 Average:110.30 Median:111.00 stddev:2.00 (1.8%) stddev-sans-first:1.87 07:21:06 INFO - PROCESS | 3667 | Values: 113.0 106.0 112.0 110.0 109.0 111.0 111.0 112.0 109.0 110.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#1] audio-beat-detection Cycles:10 Average:140.50 Median:143.00 stddev:17.80 (12.4%) stddev-sans-first:17.85 07:21:06 INFO - PROCESS | 3667 | Values: 157.0 131.0 126.0 166.0 128.0 125.0 127.0 155.0 165.0 125.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#2] audio-dft Cycles:10 Average:159.70 Median:160.00 stddev:1.34 (0.8%) stddev-sans-first:1.33 07:21:06 INFO - PROCESS | 3667 | Values: 161.0 160.0 159.0 159.0 161.0 162.0 160.0 158.0 159.0 158.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:17.42 (23.2%) stddev-sans-first:18.26 07:21:06 INFO - PROCESS | 3667 | Values: 75.0 75.0 75.0 124.0 74.0 76.0 74.0 73.0 105.0 75.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:84.00 stddev:18.48 (22.0%) stddev-sans-first:19.15 07:21:06 INFO - PROCESS | 3667 | Values: 84.0 84.0 118.0 84.0 84.0 84.0 115.0 83.0 85.0 131.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.63 (0.5%) stddev-sans-first:0.67 07:21:06 INFO - PROCESS | 3667 | Values: 117.0 118.0 117.0 117.0 117.0 118.0 118.0 116.0 117.0 117.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:1.75 (1.2%) stddev-sans-first:1.86 07:21:06 INFO - PROCESS | 3667 | Values: 146.0 146.0 145.0 146.0 146.0 151.0 146.0 145.0 145.0 146.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 07:21:06 INFO - PROCESS | 3667 | Values: 95.0 95.0 94.0 95.0 94.0 95.0 95.0 95.0 95.0 95.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#8] json-parse-financial Cycles:10 Average:51.90 Median:52.00 stddev:1.45 (2.8%) stddev-sans-first:1.36 07:21:06 INFO - PROCESS | 3667 | Values: 50.0 53.0 51.0 54.0 51.0 54.0 53.0 51.0 51.0 51.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:61.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 07:21:06 INFO - PROCESS | 3667 | Values: 60.0 61.0 61.0 61.0 60.0 61.0 60.0 60.0 61.0 60.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#10] stanford-crypto-aes Cycles:10 Average:79.40 Median:76.00 stddev:11.14 (14.7%) stddev-sans-first:11.70 07:21:06 INFO - PROCESS | 3667 | Values: 75.0 75.0 75.0 75.0 76.0 77.0 77.0 76.0 111.0 77.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#11] stanford-crypto-ccm Cycles:10 Average:121.80 Median:123.00 stddev:4.66 (3.8%) stddev-sans-first:3.56 07:21:06 INFO - PROCESS | 3667 | Values: 131.0 115.0 124.0 124.0 122.0 116.0 125.0 119.0 122.0 120.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.30 Median:156.50 stddev:2.06 (1.3%) stddev-sans-first:2.09 07:21:06 INFO - PROCESS | 3667 | Values: 158.0 155.0 161.0 156.0 155.0 155.0 157.0 155.0 157.0 154.0 07:21:06 INFO - PROCESS | 3667 | 07:21:06 INFO - PROCESS | 3667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.20 Median:61.50 stddev:1.03 (1.7%) stddev-sans-first:1.05 07:21:06 INFO - PROCESS | 3667 | Values: 62.0 61.0 61.0 60.0 61.0 62.0 60.0 60.0 62.0 63.0 07:21:06 INFO - PROCESS | 3667 | -------- Summary: end -------- 07:21:06 INFO - PROCESS | 3667 | 07:21:07 INFO - TEST-INFO | 3667: exit 0 07:21:07 INFO - TEST-OK | kraken | took 60272ms 07:21:07 INFO - SUITE-END | took 1176s 07:21:07 INFO - Completed test suite (00:19:36) 07:21:07 INFO - javascript benchmark 07:21:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.5880514935634, 3340.8830690925824, 3342.548755917952, 3352.2625022495045, 3383.323335332934, 5393.244945707599, 5486.423925487826, 5504.80733174339, 5509.393966663663, 5554.805581804638, 5471.699056551888, 5528.695921724304, 5539.944600553993, 5552.0, 5574.1917421973785, 4809.663323567352, 4860.781264843073, 4989.451160372356, 5044.722540260284, 5062.797488100471, 4884.780184891689, 4964.975175124119, 4968.205087186033, 4968.403791545002, 4968.57767089796, 4981.252812078199, 5004.799808007675, 5025.949740502585, 5062.240663900426, 5069.163588007974, 5090.440051594322, 5114.207297868855, 5115.309433226504, 5116.641835071547, 5139.306193663845, 4874.147024270738, 4920.3357546731095, 4926.704397736112, 5009.223570346602, 5032.295478632995, 5306.602004849652, 5367.919481207785, 5369.275147855029, 5390.973045134789, 5411.377691565481, 5870.941290587082, 5887.0, 5897.941020589782, 5900.4984576310635, 5923.496502797268, 5847.122931560258, 5909.2022576951995, 5923.052311630118, 5924.674142922137, 5956.16613674086, 5534.861628459321, 5546.667199967975, 5584.078627026536, 5597.860053498695, 5599.552035837123, 5830.271216097988, 6072.638210631912, 6081.27024757032, 6089.451949324582, 6097.542684298606, 5603.0, 5639.63342382752, 5647.463490968352, 5691.117876729114, 5739.426057394227, 5805.8548536286935, 5816.272965879265, 5825.679587622637, 5827.708614569255, 5832.708364581754, 5645.830625081254, 5694.572907031989, 5755.280589926259, 5762.394948530344, 5766.221560089418, 5725.227094342294, 5818.650880947157, 5822.214001109796, 5822.417758224144, 5858.091995740667, 4493.326001099861, 4496.820127194941, 4501.257292546693, 4501.7974191161475, 4524.47968483622, 3954.980225098856, 3977.204559088194, 4017.1563971565715, 4025.597440255951, 4054.2094291613444, 5557.888842223133, 5589.273394458694, 5598.804041858597, 5600.551955843523, 5612.466815652507, 5868.09044598088, 5869.8239052828485, 5879.353271140171, 5890.3815099414805, 5905.320888097838, 5417.59368047398, 5465.699386533779, 5505.862353441196, 5507.03626865297, 5580.051391263495, 5024.773885175176, 5036.798528058836, 5038.564512671171, 5051.520105589963, 5056.191009438546, 6040.607360521552, 6041.18444010053, 6052.364501727343, 6058.636481811105, 6069.332373438918, 5631.408702086222, 5644.576656750843, 5746.31044274673, 5757.337906140848, 5863.648181109147, 5968.253968253968, 5978.551608629457, 5991.071383935497, 5992.250968628921, 5992.880142397041, 5734.311882574118, 5739.0, 5739.0, 5747.224124743107, 5750.8849823002465, 5753.597248192586, 5766.538676905753, 5813.912791308218, 5817.680027598353, 5876.529877609695, 5709.628874123168, 5715.9142612860005, 5720.3421606515785, 5723.656580605177, 5723.856903577277, 5554.527865131509, 5651.265335506358, 5693.032184528723, 5704.68624225655, 5707.315122185364, 5562.360328562106, 5615.410381909841, 5639.407862174413, 5670.376258611632, 5681.119426488901, 5727.140928860705, 5777.133429985536, 5804.738786754522, 5819.94180058194, 5825.50483208932, 5414.702191379354, 5518.0, 5643.915341269966, 5664.291963504562, 5668.0], "value": 5399.186579053774, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [599.6731781179257, 625.7152995387098, 648.9026646003098, 650.9909640057912, 661.107504868428, 33141.50287745686, 45896.3115553267, 46957.0608587828, 47107.5289247107, 47135.0, 48953.51046489534, 49256.2611560826, 49279.0, 49359.75320123403, 49408.25887611679, 8480.575971201446, 9218.539073046337, 9354.532273386338, 9384.0, 10886.72783180419, 10669.733256668567, 11056.502457389397, 11150.832737508903, 11152.609658661948, 11154.553817847276, 11166.944165279163, 11189.832152517718, 11219.15856310776, 11219.214654974156, 11222.270552414107, 8412.579371031454, 11066.39134847583, 11140.442977851075, 11341.603043893507, 11410.20128590999, 10757.892421075767, 11175.44122793857, 11181.664550063511, 11190.720231994184, 11199.27204731698, 45757.54242457566, 46265.768671156766, 46363.76818115922, 46385.0, 46448.76775616134, 3938.4289278054807, 3967.7817720025387, 4069.511658600957, 4459.331100334944, 4584.1977653910435, 34285.82857085724, 35198.29603407943, 35426.46860297098, 35478.8226058868, 36093.81953090244, 4703.435587729495, 4715.646326525524, 4752.857414277577, 4754.66717329787, 4760.476347601761, 399424.0, 399752.0, 400164.0, 400168.9999999971, 400556.0, 32285.838570807464, 71095.28904710886, 71525.0, 72098.0, 72210.63894680493, 32215.35569288648, 32276.515852262233, 32348.029559113264, 32359.0, 32389.19027024343, 15618.062916225063, 15686.215689215494, 15709.293081811345, 15712.21438928049, 15729.370825167094, 13612.183269003892, 14877.256137193097, 15026.549203523911, 15094.471693490676, 15335.693286134216, 7002.929970700329, 7010.789676309617, 7011.1586609607175, 7034.402075823612, 7063.223045464994, 2796.300924768808, 2799.566067259578, 2815.4087641595083, 2827.90102346421, 2861.6852146263896, 1087.342157994418, 1105.2042529378834, 1118.429600903545, 1184.176996987103, 1199.1785626845638, 34079.97760067202, 34353.31293374168, 34587.82706086453, 34611.30777384438, 34673.47989780155, 1182.7457096724245, 1199.1426130316866, 1206.4932728254155, 1213.6055781870928, 1238.0467040378858, 18091.54771130706, 18111.366102186625, 18157.45527634173, 18226.270949161884, 18242.361517347093, 161836.1908190475, 162437.18781406016, 162511.18744406203, 163039.0, 163179.0, 63737.36262637407, 64228.67885660542, 64262.0, 64273.357266426734, 64341.678291609176, 1365.481117175467, 1414.4779499047297, 1450.7026059657744, 1476.1733429279639, 1505.3301280929882, 32250.838745806122, 35367.0, 35766.64233357633, 35775.284494309446, 36012.6591771534, 55205.44794552003, 60531.39468605434, 61870.69064654648, 61983.690081549306, 66920.33079669336, 486265.0, 491080.0, 491119.0, 491472.0, 495177.0, 36228.81885590555, 41618.375724363555, 47680.7615961918, 47979.88130771489, 48321.0, 48390.75804620954, 53142.0, 53165.0, 53409.0, 53917.19124213218, 44076.0, 44294.476092177814, 44630.3305450412, 44777.10445791131, 45539.316910247035, 42595.787021064694, 43097.353539696305, 43870.0, 43951.12097757963, 44291.11417771562, 44257.05002604206, 44577.55422445864, 44848.32727509025, 45033.0, 45370.77314613406, 38534.0, 39699.40450893181, 39902.201955961296, 40364.0, 40387.3941890866, 29466.0, 29510.852445737633, 29862.0, 30021.849890750407, 30133.698663013965], "value": 20070.485376771303, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [428.42163079842214, 430.4060396652619, 437.73079556073014, 438.3274296004592, 439.89222640453073, 19007.809921900778, 20084.497887552818, 20723.0, 20815.16739330425, 21066.157353705872, 19780.802191978113, 20128.396148115542, 20297.797022029776, 20312.0, 20366.389008329737, 5501.0648189807725, 5744.712764361765, 5779.393163717812, 5782.971085144568, 5784.0, 5645.2096706461125, 5646.18130371096, 5755.568332375088, 5766.798162064329, 5779.21980532629, 5729.914051289234, 5730.111832665943, 5732.111522713986, 5736.541076713874, 5737.512311453532, 5598.384177740445, 5711.657300561958, 5888.29340479141, 5929.733162007698, 6002.8499287517725, 5616.0, 5815.360310365856, 5822.388649191858, 5825.213596164506, 5830.621009634403, 14181.716365672732, 14415.78376324356, 14416.711665766627, 14425.711485770331, 16772.916135419244, 2348.1077190667606, 2455.5702752018324, 2494.7006359237007, 2761.765249953756, 2783.6102945587636, 10730.570777168903, 11097.056750176163, 11181.161412893984, 12130.0, 13043.152195107288, 1877.1271358818215, 1955.4329244519072, 1960.8725432846961, 2010.5275260313815, 2056.9715142428786, 79715.20284797136, 81786.59106704488, 83557.58221208856, 86107.56946215354, 87046.56476717639, 11877.524899004116, 11904.880951190551, 11909.225900316625, 11993.100517461226, 12064.698382540506, 16897.662046758996, 17003.574910627332, 17010.82989170117, 17044.48866534006, 17051.658966820592, 8955.373123881265, 9070.319726020574, 9107.499087550248, 9134.588943497558, 9341.813163736822, 8935.910640893639, 8976.237019853386, 8976.8653470198, 9072.591733372014, 9134.0, 5073.213651883964, 5073.568746656501, 5104.336436263262, 5104.515071068243, 5196.662216955886, 1985.007496251874, 1987.6124155789482, 2036.75558932929, 2047.8976051197678, 2049.723287356188, 993.9006099390003, 1005.7334806276342, 1006.0090810551601, 1076.2788931415814, 1112.7496313329436, 19667.803321966596, 19886.801131988494, 20499.8975005124, 20574.177032918516, 20574.382768516967, 1039.3971496531992, 1063.6596289187385, 1064.292245656645, 1070.0797314309686, 1101.730076131353, 11235.83146252807, 11403.600873969372, 11420.200585958903, 11519.539218431337, 11525.942370288263, 486875.0, 491534.0, 500288.0, 512612.0, 515757.0, 53629.731851341276, 72037.6398118006, 94075.0, 95744.0, 95817.52091239499, 1050.9789804203874, 1057.6245432871374, 1070.7002039428817, 1072.5495291977388, 1078.9298695584605, 17534.473965781046, 17735.64528709393, 17816.821831781515, 17902.0, 18179.18193681314, 39245.0, 39766.40350394689, 40625.0, 41683.37474937939, 41788.0, 58090.709546451995, 58673.413265868505, 59451.40548594459, 60241.69879150576, 61320.69339653273, 8704.204818916183, 8821.103462755282, 8849.734507964771, 8926.152015558511, 9025.684101056431, 12254.448549815457, 13057.477700892237, 13112.409941552462, 13159.407826648016, 13431.462741490232, 6341.460975817107, 10361.689149325532, 11374.43127843621, 11809.763804723685, 11879.643610691694, 11457.312561246352, 11493.425328733698, 11522.942385288019, 11563.768724625628, 11602.07183425341, 11209.71975700614, 11935.462904169506, 12022.75954480923, 12079.154459188125, 12108.394580271128, 8714.302855771397, 8853.114688531095, 8880.97352376503, 9024.323175761712, 9196.26429885621, 11254.324740515594, 11846.940765296464, 11859.407029648655, 11870.228435152034, 12308.076894232789], "value": 9428.635893623474, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.9655508612277, 1501.0393348257114, 1558.2987655555, 1723.4915699868538, 1866.6733321668705, 8726.171013753692, 9098.636054557817, 9106.225970792473, 9296.674616388427, 9303.348765586412, 9088.363814532986, 9266.0, 9269.397489163199, 9278.304127190453, 9289.396189247695, 2152.4080877758665, 2401.7598240176017, 2420.8063354931564, 2431.0397393029743, 2503.812214083942, 2447.4615584571457, 2490.0, 2491.9377015574664, 2501.774840264376, 2503.6494890715317, 2440.6827112475444, 2468.5803413419762, 2470.8023358131304, 2473.480569080495, 2477.1453848422357, 2127.585120901425, 2351.1535847095033, 2426.065964603623, 2427.635854621803, 2442.7679370459778, 2381.57131716291, 2387.307680772574, 2387.9612368619705, 2408.4821763320797, 2437.488127493211, 9569.712908612753, 9606.615735370577, 9609.99095095012, 9625.229981601455, 9625.71122866315, 5452.91820622691, 5599.7480113394995, 5601.187827764992, 5606.551475881961, 5607.691576963265, 3365.96634033659, 3441.6042155152227, 3490.912727181815, 3521.049316684481, 3522.5420695309704, 1120.8318752187156, 1125.898669119775, 1126.9098472122212, 1128.2328016948472, 1171.168470386027, 13742.175469471764, 13817.723645527134, 13820.101693389897, 13858.307084645829, 13862.514811981533, 4909.189983652657, 4947.381577302765, 4958.9504104959215, 4972.602191824645, 5061.7975280989085, 11479.483423245807, 11538.423078846025, 11549.01833344175, 11558.364289963965, 11565.710857228469, 4964.726940018261, 5644.209810626516, 5650.378458369576, 5658.886822263532, 5664.0, 4620.745858977722, 4622.168009758276, 4623.052274283764, 5005.124103281911, 5229.790808367623, 4166.166766646683, 4263.893402664959, 4334.219840428753, 4381.0, 4386.605205531518, 1258.2324781883044, 1323.503686117706, 1325.436689406998, 1334.8398192216803, 1338.0967846703436, 1007.9395236285847, 1016.9288149829441, 1025.789713108826, 1035.7048241251357, 1052.095198129608, 6204.441600255998, 6229.3770622937345, 6231.096491008779, 6285.2457705075685, 6329.050642403677, 475.38437723148604, 478.7917255993654, 479.5635971266201, 486.5864015586723, 501.4033300372545, 4363.127374525045, 4397.73613583186, 4410.33844923264, 4420.425344705232, 4451.910961780746, 11651.359175245274, 11963.282203067844, 12013.579524716593, 12020.038396928225, 12055.095867809949, 11759.176857619885, 11831.230969986924, 11831.940840295743, 11877.940610297066, 11898.46456909441, 8499.405041647025, 8507.70223042203, 8818.11818818113, 8820.779480513038, 9043.18611324984, 6481.833270011443, 6746.69639866217, 6826.385625293548, 6869.862602748017, 6916.20463646687, 10584.153267738717, 10937.507812148313, 11028.117750580095, 11059.612913547988, 11137.888621114009, 11036.0, 11287.830682539932, 11299.943500282447, 11313.264637798517, 11456.427178640868, 8528.360372972176, 8696.086910874266, 8741.694040708544, 8839.0, 8840.690575829814, 9370.765730856783, 9379.906200938176, 9396.107369799858, 9419.576119074794, 9467.573959171716, 8557.657693692183, 8586.0, 8633.481991080555, 8675.522846243264, 8742.781430464289, 8072.112067672669, 8157.143499932422, 8287.295579875778, 8291.212334828182, 8559.058503564727, 7767.495112817649, 7861.8034549134445, 7862.685492580462, 7868.527888326718, 7869.567173805495, 5462.562994960314, 5593.608447408642, 5648.209250704823, 5669.971650141723, 5755.395683453345, 5357.437469065692, 5393.83818485446, 5495.450454954473, 5508.531774799027, 5597.132444471114], "value": 4870.562903498568, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.73704116350996, 359.8524604911985, 361.94027985382405, 362.7424528584705, 363.521968611276, 5200.815657423882, 5336.332958380202, 5398.325209348832, 5431.809886653968, 5461.535769459596, 5369.40936496986, 5373.0, 5383.246345511631, 5400.054990376678, 5424.891502169954, 4281.422008028923, 4303.526612072686, 4313.935290970638, 4324.416203812477, 4342.435483387155, 4058.492688413948, 4165.520965089023, 4280.4221430106845, 4322.589354011364, 4345.956540434602, 4235.894102647428, 4236.661067114624, 4268.893277668052, 4271.739836748166, 4283.721558098714, 4238.851640192594, 4243.533211346749, 4251.87244382669, 4256.659467242614, 4276.764777937212, 3679.981600092009, 3767.717421193394, 3831.233753249361, 3834.501514803058, 3857.864974725871, 5073.771680274396, 5081.822136225214, 5096.974515127437, 5116.590672746172, 5142.125838607409, 4878.878028049292, 4884.926726099111, 4901.411830580318, 4901.779419926112, 4913.189323761575, 5371.785128594852, 5642.746076426571, 5653.275750895975, 5670.53331800668, 5676.914846277309, 4048.1498885234137, 4072.1652061327654, 4072.3687828386646, 4074.8166332515107, 4076.6331030207125, 5825.388334224887, 5862.237909071836, 5863.0, 5878.382769809194, 5897.64614123154, 3969.920601587952, 4160.895977600584, 4183.91632167355, 4184.790760462026, 4207.116505533811, 4181.832726690959, 4193.972476743217, 4195.895102622398, 4201.432806571073, 4233.70364074518, 4768.785404656799, 4776.8566942991765, 4784.760761961888, 4788.425388953278, 4794.376731025014, 5558.388577256498, 5628.521575666033, 5630.74661640219, 5670.291213598301, 5677.489025987598, 1399.237415608496, 1441.891858110646, 1462.992685036568, 1463.8023866778062, 1475.1075599262292, 925.7500474871747, 941.110650435332, 943.9150476457014, 946.966856160031, 953.3803028031834, 4907.607391408679, 4924.581410580069, 4950.727709975986, 4960.330355402047, 4972.577330926912, 5129.692218466904, 5155.74221288934, 5164.321595481409, 5164.896702066013, 5210.6873587584105, 4716.764161791897, 4843.39457567804, 4880.341153944243, 4909.69892978361, 5006.724630145304, 2722.850243236641, 2788.916332510028, 2796.979102627526, 2800.8376523742418, 2802.9299267518477, 5246.580273578104, 5280.339957505312, 5289.3917199521775, 5318.0, 5329.173978033411, 4777.020710754287, 5129.794808207705, 5135.281060651511, 5192.767110142675, 5243.5542978847225, 5242.61070816234, 5278.419373868871, 5309.8938021239355, 5316.308879845596, 5318.601104917069, 5146.511081447256, 5161.922571161361, 5171.896562068813, 5173.697704521603, 5187.92218116721, 5066.82266120684, 5318.494742999409, 5816.912746308724, 5823.563232757476, 5826.329972053268, 6015.518758499222, 6030.939690603213, 6056.606320589148, 6093.48205402528, 6098.237720284965, 4191.497020357577, 4239.872803815891, 4259.765712885697, 4267.3812297217355, 4272.764997925144, 5617.303694226875, 5665.065264231355, 5813.069908814569, 5824.184614153937, 5856.824295271149, 5633.211350410864, 5656.434356564475, 5665.49010589049, 5673.631213971079, 5677.290338707661, 5632.464915832989, 5680.744366503434, 5683.971580142238, 5686.09022556389, 5689.630174038674, 5415.59383046281, 5550.390386787948, 5569.33168019824, 5584.213051823377, 5598.880223955144, 5612.0, 5669.262995810518, 5673.5461163107675, 5686.663634046078, 5687.20379146928, 5342.8931421372135, 5517.0, 5544.972275138599, 5560.388357280615, 5576.972115139399], "value": 4220.702239565344, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.2021538031208, 786.909505406878, 803.8432505661391, 853.0701535326493, 881.3918396306548, 15065.698686026286, 16058.357665693373, 17402.999999999985, 17416.0, 17449.47651570452, 15915.204239788021, 15920.681586368266, 15995.520134395958, 16010.119443430607, 16077.11575863327, 719.3418022509397, 734.4308161175105, 735.1949615171379, 735.9116905971291, 840.0339609449152, 866.6620018192879, 873.2184694698241, 874.2961915657893, 874.7856775090112, 875.3128793896792, 901.4050726520497, 907.5825120444604, 907.6505545365048, 908.8954770201412, 909.4134283387211, 742.1428250370831, 879.766861781622, 887.6227603268585, 892.9508877011762, 894.3694695239873, 665.8801415745164, 666.3070406776994, 667.0994157886862, 667.1760375935716, 667.5260564998856, 15078.773818392732, 15106.320215590215, 15114.168720720356, 15133.0, 15153.469628562947, 14680.853191468163, 15145.924270378688, 15264.847351526454, 15295.464658737003, 15356.616084597863, 13640.113392629448, 13713.108647937852, 13853.445862165601, 14021.158730476203, 14453.494127705584, 4759.785809638574, 4769.976150119227, 4810.927836082462, 4814.422269327668, 4974.0797952378925, 76412.0, 76597.0, 76674.0, 76729.0, 77157.22842771557, 11133.387663678413, 11907.821382679267, 12085.335306558049, 12102.33437160947, 12185.573504927284, 24205.63691544628, 24387.14644987417, 24518.632220516705, 24531.0, 24604.384890377525, 9131.954340228256, 9137.08629137081, 9155.587998540082, 9168.908310916806, 9214.907850921405, 4293.291606884892, 4904.4605093439695, 5049.848504544796, 5057.848264552069, 5060.503868280118, 3727.4408838674417, 3877.515310586177, 3879.127196380848, 3884.378499440076, 3886.591907849635, 537.9784808607612, 643.2152773616181, 649.0815496073099, 649.5518092516148, 651.205529254309, 632.7009583414789, 665.8768127896354, 687.8555503344354, 701.6071000239882, 809.995950020258, 9724.664898003763, 9749.0, 9961.55173017216, 10105.646302379382, 10113.089821916063, 707.3245050976305, 735.0664655886993, 762.2910693055401, 806.4838503357855, 836.9037560680478, 4635.3742244797195, 4693.718376897329, 4715.976420117878, 4818.445878723922, 4845.079434907357, 78398.6080069596, 125090.0, 125098.37450812869, 127339.0, 130080.0, 107574.0, 107752.0, 108944.0, 110692.44653776841, 112049.43975280071, 60747.0, 62882.68558657269, 68941.65529172322, 92602.99999999865, 94867.52566237125, 9950.502474876372, 9997.0, 10010.0, 10011.349262297927, 10329.225308102074, 50125.0, 51870.22194667158, 55880.0, 56246.87506249934, 56909.715451422475, 73319.63340183265, 81547.18452815396, 83004.0, 83417.58291208505, 84347.0, 12612.87387126117, 12658.620241392773, 12741.235525868478, 13105.934470327587, 13209.207447553177, 13980.44078236859, 14123.929380353033, 14349.35427905726, 14387.856121438652, 14417.207053612152, 13256.0, 13329.133606315558, 13486.190828550318, 13782.517611883535, 13857.930710346787, 13295.933520332337, 13620.727585448434, 13693.726125477235, 13710.588682339545, 14111.929440352733, 13090.607281781562, 13184.670383240496, 13422.134191631592, 13553.796693049415, 13824.516141934984, 10927.453627318444, 10979.945100274448, 11019.228653994143, 11026.117910567296, 11484.253523520945, 9922.077145121888, 10435.582576696846, 11074.39090850011, 11321.433928303717, 11421.657350279505], "value": 7853.8448951460105, "unit": "score"}], "name": "dromaeo_css", "value": 7405.7801655082385, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 110.0, 109.0, 111.0, 111.0, 112.0, 109.0, 110.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 131.0, 126.0, 166.0, 128.0, 125.0, 127.0, 155.0, 165.0, 125.0], "value": 140.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 159.0, 161.0, 162.0, 160.0, 158.0, 159.0, 158.0], "value": 159.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 124.0, 74.0, 76.0, 74.0, 73.0, 105.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 118.0, 84.0, 84.0, 84.0, 115.0, 83.0, 85.0, 131.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 118.0, 118.0, 116.0, 117.0, 117.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 146.0, 146.0, 151.0, 146.0, 145.0, 145.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 53.0, 51.0, 54.0, 51.0, 54.0, 53.0, 51.0, 51.0, 51.0], "value": 51.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 60.0, 61.0, 60.0, 60.0, 61.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 75.0, 76.0, 77.0, 77.0, 76.0, 111.0, 77.0], "value": 79.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 115.0, 124.0, 124.0, 122.0, 116.0, 125.0, 119.0, 122.0, 120.0], "value": 121.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 155.0, 161.0, 156.0, 155.0, 155.0, 157.0, 155.0, 157.0, 154.0], "value": 156.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 61.0, 60.0, 61.0, 62.0, 60.0, 60.0, 62.0, 63.0], "value": 61.2, "unit": "score"}], "name": "kraken", "value": 1477.6000000000001, "alertThreshold": 2.0}]} 07:21:07 INFO - Return code: 0 07:21:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:21:07 ERROR - # TBPL SUCCESS # 07:21:07 INFO - Running post-action listener: _resource_record_post_action 07:21:07 INFO - ##### 07:21:07 INFO - ##### Finished run-tests step (success) 07:21:07 INFO - ##### 07:21:07 INFO - Running post-run listener: _resource_record_post_run 07:21:08 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 7958528; Write bytes: 396902400; Read time: 1808; Write time: 517180 07:21:08 INFO - TinderboxPrint: CPU usage
13.1% 07:21:08 INFO - TinderboxPrint: I/O read bytes / time
7,958,528 / 1,808 07:21:08 INFO - TinderboxPrint: I/O write bytes / time
396,902,400 / 517,180 07:21:08 INFO - TinderboxPrint: CPU idle
8,272.2 (86.9%) 07:21:08 INFO - TinderboxPrint: CPU user
1,200.0 (12.6%) 07:21:08 INFO - TinderboxPrint: Swap in / out
0 / 0 07:21:08 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 20729856; Read time: 0; Write time: 28976 07:21:08 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 6066176; Write bytes: 376172544; Read time: 1360; Write time: 488204 07:21:08 INFO - Running post-run listener: _upload_blobber_files 07:21:08 INFO - Blob upload gear active. 07:21:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:21:08 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 07:21:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:21:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:21:09 INFO - (blobuploader) - INFO - Open directory for files ... 07:21:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:21:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:21:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:21:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:21:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:21:09 INFO - (blobuploader) - INFO - Done attempting. 07:21:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:21:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:21:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:21:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:21:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:21:13 INFO - (blobuploader) - INFO - Done attempting. 07:21:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:21:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:21:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:21:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:21:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:21:14 INFO - (blobuploader) - INFO - Done attempting. 07:21:14 INFO - (blobuploader) - INFO - Iteration through files over. 07:21:14 INFO - Return code: 0 07:21:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:21:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:21:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/475b96e4dabb1b5da9301bae55e26ffb63c6bd1bd6785e5e1b5793321d2b8cf30f12e23abeb6e070ff6b700e115248bb53c5f5be13da592bc72042cf73317475", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c00c2be0d8e11ae33da2cf35b31440fb7c9b89dced13bb86d3e5087d5a57769c3febac94037f375146fc44fc4178fe96fcc2548dcaed566524e6273dfbc9dad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b98361b82a58b7e35e61295ab1c425057fca2dd4558c6e4933583e4e95bd5cc60c0fbf5269e3ec349842d4f588adc68660a49aa9b03f9bf7619ead2f4e44401"} 07:21:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:21:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:21:14 INFO - Contents: 07:21:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/475b96e4dabb1b5da9301bae55e26ffb63c6bd1bd6785e5e1b5793321d2b8cf30f12e23abeb6e070ff6b700e115248bb53c5f5be13da592bc72042cf73317475", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c00c2be0d8e11ae33da2cf35b31440fb7c9b89dced13bb86d3e5087d5a57769c3febac94037f375146fc44fc4178fe96fcc2548dcaed566524e6273dfbc9dad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b98361b82a58b7e35e61295ab1c425057fca2dd4558c6e4933583e4e95bd5cc60c0fbf5269e3ec349842d4f588adc68660a49aa9b03f9bf7619ead2f4e44401"} 07:21:14 INFO - Running post-run listener: copy_logs_to_upload_dir 07:21:14 INFO - Copying logs to upload dir... 07:21:14 INFO - mkdir: /builds/slave/test/build/upload/logs 07:21:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.775403 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-07-15 07:21:14.100006) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 07:21:14.103407) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/475b96e4dabb1b5da9301bae55e26ffb63c6bd1bd6785e5e1b5793321d2b8cf30f12e23abeb6e070ff6b700e115248bb53c5f5be13da592bc72042cf73317475", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c00c2be0d8e11ae33da2cf35b31440fb7c9b89dced13bb86d3e5087d5a57769c3febac94037f375146fc44fc4178fe96fcc2548dcaed566524e6273dfbc9dad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b98361b82a58b7e35e61295ab1c425057fca2dd4558c6e4933583e4e95bd5cc60c0fbf5269e3ec349842d4f588adc68660a49aa9b03f9bf7619ead2f4e44401"} build_url:https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011378 build_url: 'https://queue.taskcluster.net/v1/task/TdIqZyn3TVOdo_bIL_m0gw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/475b96e4dabb1b5da9301bae55e26ffb63c6bd1bd6785e5e1b5793321d2b8cf30f12e23abeb6e070ff6b700e115248bb53c5f5be13da592bc72042cf73317475", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3c00c2be0d8e11ae33da2cf35b31440fb7c9b89dced13bb86d3e5087d5a57769c3febac94037f375146fc44fc4178fe96fcc2548dcaed566524e6273dfbc9dad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6b98361b82a58b7e35e61295ab1c425057fca2dd4558c6e4933583e4e95bd5cc60c0fbf5269e3ec349842d4f588adc68660a49aa9b03f9bf7619ead2f4e44401"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 07:21:14.129148) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:21:14.129473) ========= 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=05b19d94dfa9f3ce7b6e62cc000001f7-1468590702.232555-523639894 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004358 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 07:21:14.214031) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 07:21:14.214367) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 07:21:14.214699) ========= ========= Total master_lag: 0.52 =========