builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-022 starttime: 1469112177.09 results: success (0) buildid: 20160721050633 builduid: 10f9b5a4548b454c869161874e920c30 revision: 929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.090454) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.090977) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.091273) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.107182) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.107539) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-07-21 07:42:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 46.7M=0s 2016-07-21 07:42:57 (46.7 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117323 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.239041) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.239401) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018100 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.289503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.289893) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8 --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 929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8 --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=9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-07-21 07:42:57,380 truncating revision to first 12 chars 2016-07-21 07:42:57,380 Setting DEBUG logging. 2016-07-21 07:42:57,380 attempt 1/10 2016-07-21 07:42:57,380 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/929a78239631?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-21 07:42:57,706 unpacking tar archive at: mozilla-aurora-929a78239631/testing/mozharness/ program finished with exit code 0 elapsedTime=0.663352 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.967083) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.967414) ========= script_repo_revision: 929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.967845) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.968129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-21 07:42:57.976852) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-21 07:42:57.977174) ========= /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=9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 07:42:58 INFO - MultiFileLogger online at 20160721 07:42:58 in /builds/slave/test 07:42:58 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:42:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:42:58 INFO - {'append_to_log': False, 07:42:58 INFO - 'base_work_dir': '/builds/slave/test', 07:42:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:42:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:42:58 INFO - 'branch': 'Mozilla-Aurora', 07:42:58 INFO - 'buildbot_json_path': 'buildprops.json', 07:42:58 INFO - 'config_files': ('talos/linux_config.py',), 07:42:58 INFO - 'default_actions': ('clobber', 07:42:58 INFO - 'read-buildbot-config', 07:42:58 INFO - 'download-and-extract', 07:42:58 INFO - 'populate-webroot', 07:42:58 INFO - 'create-virtualenv', 07:42:58 INFO - 'install', 07:42:58 INFO - 'run-tests'), 07:42:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:42:58 INFO - 'download_minidump_stackwalk': True, 07:42:58 INFO - 'download_symbols': 'ondemand', 07:42:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:42:58 INFO - 'tooltool.py': '/tools/tooltool.py', 07:42:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:42:58 INFO - '/tools/misc-python/virtualenv.py')}, 07:42:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:42:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:42:58 INFO - 'installer_path': 'installer.exe', 07:42:58 INFO - 'log_level': 'info', 07:42:58 INFO - 'log_name': 'talos', 07:42:58 INFO - 'log_to_console': True, 07:42:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 07:42:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 07:42:58 INFO - 'opt_config_files': (), 07:42:58 INFO - 'pip_index': False, 07:42:58 INFO - 'sps_profile': False, 07:42:58 INFO - 'sps_profile_interval': 0, 07:42:58 INFO - 'suite': 'dromaeojs', 07:42:58 INFO - 'system_bits': '32', 07:42:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:42:58 INFO - 'title': 'talos-linux64-ix-022', 07:42:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:42:58 INFO - 'use_talos_json': True, 07:42:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:42:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:42:58 INFO - 'work_dir': 'build'} 07:42:58 INFO - ##### 07:42:58 INFO - ##### Running clobber step. 07:42:58 INFO - ##### 07:42:58 INFO - Running pre-action listener: _resource_record_pre_action 07:42:58 INFO - Running main action method: clobber 07:42:58 INFO - rmtree: /builds/slave/test/build 07:42:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:42:58 INFO - Running post-action listener: _resource_record_post_action 07:42:58 INFO - ##### 07:42:58 INFO - ##### Finished clobber step (success) 07:42:58 INFO - ##### 07:42:58 INFO - ##### 07:42:58 INFO - ##### Running read-buildbot-config step. 07:42:58 INFO - ##### 07:42:58 INFO - Running pre-action listener: _resource_record_pre_action 07:42:58 INFO - Running main action method: read_buildbot_config 07:42:58 INFO - Using buildbot properties: 07:42:58 INFO - { 07:42:58 INFO - "project": "", 07:42:58 INFO - "product": "firefox", 07:42:58 INFO - "script_repo_revision": "production", 07:42:58 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 07:42:58 INFO - "repository": "", 07:42:58 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 07:42:58 INFO - "buildid": "20160721050633", 07:42:58 INFO - "pgo_build": "True", 07:42:58 INFO - "basedir": "/builds/slave/test", 07:42:58 INFO - "buildnumber": 618, 07:42:58 INFO - "slavename": "talos-linux64-ix-022", 07:42:58 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 07:42:58 INFO - "platform": "ubuntu64_hw", 07:42:58 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 07:42:58 INFO - "branch": "mozilla-aurora", 07:42:58 INFO - "repo_path": "releases/mozilla-aurora", 07:42:58 INFO - "revision": "929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8", 07:42:58 INFO - "stage_platform": "linux64-pgo", 07:42:58 INFO - "builduid": "10f9b5a4548b454c869161874e920c30", 07:42:58 INFO - "slavebuilddir": "test" 07:42:58 INFO - } 07:42:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 07:42:58 INFO - Running post-action listener: _resource_record_post_action 07:42:58 INFO - ##### 07:42:58 INFO - ##### Finished read-buildbot-config step (success) 07:42:58 INFO - ##### 07:42:58 INFO - ##### 07:42:58 INFO - ##### Running download-and-extract step. 07:42:58 INFO - ##### 07:42:58 INFO - Running pre-action listener: _resource_record_pre_action 07:42:58 INFO - Running main action method: download_and_extract 07:42:58 INFO - mkdir: /builds/slave/test/build/tests 07:42:58 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:42:58 INFO - https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 07:42:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:42:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:42:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:42:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:42:59 INFO - Downloaded 1361 bytes. 07:42:59 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 07:42:59 INFO - Using the following test package requirements: 07:42:59 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 07:42:59 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 07:42:59 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 07:42:59 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'jsshell-linux-x86_64.zip'], 07:42:59 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 07:42:59 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 07:42:59 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 07:42:59 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 07:42:59 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 07:42:59 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 07:42:59 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 07:42:59 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 07:42: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:42:59 INFO - https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:42:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:42:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:42:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:42:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:00 INFO - Downloaded 24489767 bytes. 07:43:00 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:43:00 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:43:01 INFO - Return code: 0 07:43:01 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:43:01 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:43:01 INFO - https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 07:43:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:43:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 07:43:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:02 INFO - Downloaded 24489767 bytes. 07:43:02 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:43:02 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:43:03 INFO - Return code: 0 07:43: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:43:03 INFO - https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 07:43:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:43:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 07:43:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:04 INFO - Downloaded 10833369 bytes. 07:43:04 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:43:04 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:43:04 INFO - Return code: 0 07:43:04 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:43:04 INFO - https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 07:43:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:43:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:43:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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:43:06 INFO - Downloaded 63643217 bytes. 07:43:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:43:06 INFO - mkdir: /builds/slave/test/properties 07:43:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:43:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:43:06 INFO - Contents: 07:43:06 INFO - build_url:https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 07:43:06 INFO - Running post-action listener: _resource_record_post_action 07:43:06 INFO - Running post-action listener: set_extra_try_arguments 07:43:06 INFO - ##### 07:43:06 INFO - ##### Finished download-and-extract step (success) 07:43:06 INFO - ##### 07:43:06 INFO - ##### 07:43:06 INFO - ##### Running populate-webroot step. 07:43:06 INFO - ##### 07:43:06 INFO - Running pre-action listener: _resource_record_pre_action 07:43:06 INFO - Running main action method: populate_webroot 07:43:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:43:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:43:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:43:06 INFO - '--tppagecycles', 07:43:06 INFO - '7'], 07:43:06 INFO - 'tests': ['tsvgm']}}, 07:43:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:43:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:43:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:43:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:43:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:43:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['damp', 'tps']}, 07:43:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'tests': ['damp', 'tps']}, 07:43:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['dromaeo_dom']}, 07:43:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:43:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['a11yr', 07:43:06 INFO - 'ts_paint', 07:43:06 INFO - 'tpaint', 07:43:06 INFO - 'sessionrestore', 07:43:06 INFO - 'sessionrestore_no_auto_restore', 07:43:06 INFO - 'tabpaint']}, 07:43:06 INFO - 'other-e10s': {'tests': ['a11yr', 07:43:06 INFO - 'ts_paint', 07:43:06 INFO - 'tpaint', 07:43:06 INFO - 'sessionrestore', 07:43:06 INFO - 'sessionrestore_no_auto_restore', 07:43:06 INFO - 'tabpaint']}, 07:43:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:43:06 INFO - 'ts_paint', 07:43:06 INFO - 'tpaint', 07:43:06 INFO - 'sessionrestore', 07:43:06 INFO - 'sessionrestore_no_auto_restore', 07:43:06 INFO - 'tabpaint']}, 07:43:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:43:06 INFO - 'ts_paint', 07:43:06 INFO - 'tpaint', 07:43:06 INFO - 'sessionrestore', 07:43:06 INFO - 'sessionrestore_no_auto_restore', 07:43:06 INFO - 'tabpaint']}, 07:43:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['a11yr', 07:43:06 INFO - 'ts_paint', 07:43:06 INFO - 'tpaint', 07:43:06 INFO - 'sessionrestore', 07:43:06 INFO - 'sessionrestore_no_auto_restore', 07:43:06 INFO - 'tabpaint']}, 07:43:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['a11yr', 07:43:06 INFO - 'ts_paint', 07:43:06 INFO - 'tpaint', 07:43:06 INFO - 'sessionrestore', 07:43:06 INFO - 'sessionrestore_no_auto_restore', 07:43:06 INFO - 'tabpaint']}, 07:43:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['tsvgx', 07:43:06 INFO - 'tsvgr_opacity', 07:43:06 INFO - 'tart', 07:43:06 INFO - 'tscrollx', 07:43:06 INFO - 'cart']}, 07:43:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:43:06 INFO - 'tsvgr_opacity', 07:43:06 INFO - 'tart', 07:43:06 INFO - 'tscrollx', 07:43:06 INFO - 'cart']}, 07:43:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'talos_options': ['--disable-e10s'], 07:43:06 INFO - 'tests': ['tp5o']}, 07:43:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'tests': ['tp5o']}, 07:43:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'talos_options': ['--disable-e10s', 07:43:06 INFO - '--xperf_path', 07:43:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:06 INFO - 'tests': ['tp5n']}, 07:43:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:43:06 INFO - 'talos_options': ['--xperf_path', 07:43:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:43:06 INFO - 'tests': ['tp5n']}}, 07:43:06 INFO - 'talos.zip': {'path': '', 07:43:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:43:06 INFO - Running post-action listener: _resource_record_post_action 07:43:06 INFO - ##### 07:43:06 INFO - ##### Finished populate-webroot step (success) 07:43:06 INFO - ##### 07:43:06 INFO - ##### 07:43:06 INFO - ##### Running create-virtualenv step. 07:43:06 INFO - ##### 07:43:06 INFO - Running pre-action listener: _resource_record_pre_action 07:43:06 INFO - Running main action method: create_virtualenv 07:43:06 INFO - Creating virtualenv /builds/slave/test/build/venv 07:43:06 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:43:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:43:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:43:07 INFO - Using real prefix '/usr' 07:43:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:43:07 INFO - Installing distribute.............................................................................................................................................................................................done. 07:43:09 INFO - Installing pip.................done. 07:43:09 INFO - Return code: 0 07:43:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:09 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:09 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:43:09 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:43:09 INFO - Using env: {'DISPLAY': ':0', 07:43:09 INFO - 'HOME': '/home/cltbld', 07:43:09 INFO - 'LANG': 'en_US.UTF-8', 07:43:09 INFO - 'LANGUAGE': 'en_US:en', 07:43:09 INFO - 'LOGNAME': 'cltbld', 07:43:09 INFO - 'MAIL': '/var/mail/cltbld', 07:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:09 INFO - 'MOZ_NO_REMOTE': '1', 07:43:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:09 INFO - 'NO_EM_RESTART': '1', 07:43:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:09 INFO - 'PWD': '/builds/slave/test', 07:43:09 INFO - 'SHELL': '/bin/bash', 07:43:09 INFO - 'SHLVL': '1', 07:43:09 INFO - 'TERM': 'linux', 07:43:09 INFO - 'TMOUT': '86400', 07:43:09 INFO - 'USER': 'cltbld', 07:43:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:09 INFO - '_': '/tools/buildbot/bin/python'} 07:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:09 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:43:09 INFO - Cleaning up... 07:43:09 INFO - Return code: 0 07:43:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:09 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:09 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:43:09 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:43:09 INFO - Using env: {'DISPLAY': ':0', 07:43:09 INFO - 'HOME': '/home/cltbld', 07:43:09 INFO - 'LANG': 'en_US.UTF-8', 07:43:09 INFO - 'LANGUAGE': 'en_US:en', 07:43:09 INFO - 'LOGNAME': 'cltbld', 07:43:09 INFO - 'MAIL': '/var/mail/cltbld', 07:43:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:09 INFO - 'MOZ_NO_REMOTE': '1', 07:43:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:09 INFO - 'NO_EM_RESTART': '1', 07:43:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:09 INFO - 'PWD': '/builds/slave/test', 07:43:09 INFO - 'SHELL': '/bin/bash', 07:43:09 INFO - 'SHLVL': '1', 07:43:09 INFO - 'TERM': 'linux', 07:43:09 INFO - 'TMOUT': '86400', 07:43:09 INFO - 'USER': 'cltbld', 07:43:09 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:09 INFO - '_': '/tools/buildbot/bin/python'} 07:43:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:09 INFO - Downloading/unpacking psutil>=3.1.1 07:43:09 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:43:09 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:43:09 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:43:09 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:43:09 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:43:09 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:43:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:43:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:11 INFO - Installing collected packages: psutil 07:43:11 INFO - Running setup.py install for psutil 07:43:12 INFO - building 'psutil._psutil_linux' extension 07:43:12 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:43:12 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:43:12 INFO - building 'psutil._psutil_posix' extension 07:43:12 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:43:12 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:43:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:43:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:43:12 INFO - Successfully installed psutil 07:43:12 INFO - Cleaning up... 07:43:12 INFO - Return code: 0 07:43:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:12 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:12 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:43:12 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:43:12 INFO - Using env: {'DISPLAY': ':0', 07:43:12 INFO - 'HOME': '/home/cltbld', 07:43:12 INFO - 'LANG': 'en_US.UTF-8', 07:43:12 INFO - 'LANGUAGE': 'en_US:en', 07:43:12 INFO - 'LOGNAME': 'cltbld', 07:43:12 INFO - 'MAIL': '/var/mail/cltbld', 07:43:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:12 INFO - 'MOZ_NO_REMOTE': '1', 07:43:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:12 INFO - 'NO_EM_RESTART': '1', 07:43:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:12 INFO - 'PWD': '/builds/slave/test', 07:43:12 INFO - 'SHELL': '/bin/bash', 07:43:12 INFO - 'SHLVL': '1', 07:43:12 INFO - 'TERM': 'linux', 07:43:12 INFO - 'TMOUT': '86400', 07:43:12 INFO - 'USER': 'cltbld', 07:43:12 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:12 INFO - '_': '/tools/buildbot/bin/python'} 07:43:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:43: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:43: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:43: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:43: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:43: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:43: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:43:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:43:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:43:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:43:14 INFO - Installing collected packages: mozsystemmonitor 07:43:14 INFO - Running setup.py install for mozsystemmonitor 07:43:14 INFO - Successfully installed mozsystemmonitor 07:43:14 INFO - Cleaning up... 07:43:14 INFO - Return code: 0 07:43:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:14 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:14 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:43:14 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:43:14 INFO - Using env: {'DISPLAY': ':0', 07:43:14 INFO - 'HOME': '/home/cltbld', 07:43:14 INFO - 'LANG': 'en_US.UTF-8', 07:43:14 INFO - 'LANGUAGE': 'en_US:en', 07:43:14 INFO - 'LOGNAME': 'cltbld', 07:43:14 INFO - 'MAIL': '/var/mail/cltbld', 07:43:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:14 INFO - 'MOZ_NO_REMOTE': '1', 07:43:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:14 INFO - 'NO_EM_RESTART': '1', 07:43:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:14 INFO - 'PWD': '/builds/slave/test', 07:43:14 INFO - 'SHELL': '/bin/bash', 07:43:14 INFO - 'SHLVL': '1', 07:43:14 INFO - 'TERM': 'linux', 07:43:14 INFO - 'TMOUT': '86400', 07:43:14 INFO - 'USER': 'cltbld', 07:43:14 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:14 INFO - '_': '/tools/buildbot/bin/python'} 07:43:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:15 INFO - Downloading/unpacking jsonschema==2.5.1 07:43:15 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:43:15 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:43:15 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:43:15 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:43:15 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:43:15 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:43:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:43:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:43:16 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:43:16 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:43:16 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:43:16 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:43:16 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:43:16 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:43:17 INFO - Downloading functools32-3.2.3-2.tar.gz 07:43:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:43:17 INFO - warning: no files found matching '*.txt' 07:43:17 INFO - no previously-included directories found matching 'build' 07:43:17 INFO - no previously-included directories found matching 'dist' 07:43:17 INFO - no previously-included directories found matching '.git*' 07:43:17 INFO - Installing collected packages: jsonschema, functools32 07:43:17 INFO - Running setup.py install for functools32 07:43:17 INFO - warning: no files found matching '*.txt' 07:43:17 INFO - no previously-included directories found matching 'build' 07:43:17 INFO - no previously-included directories found matching 'dist' 07:43:17 INFO - no previously-included directories found matching '.git*' 07:43:17 INFO - Successfully installed jsonschema functools32 07:43:17 INFO - Cleaning up... 07:43:17 INFO - Return code: 0 07:43:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:43:17 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:43:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:17 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:43:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:17 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:17 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:43:17 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:43:17 INFO - Using env: {'DISPLAY': ':0', 07:43:17 INFO - 'HOME': '/home/cltbld', 07:43:17 INFO - 'LANG': 'en_US.UTF-8', 07:43:17 INFO - 'LANGUAGE': 'en_US:en', 07:43:17 INFO - 'LOGNAME': 'cltbld', 07:43:17 INFO - 'MAIL': '/var/mail/cltbld', 07:43:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:17 INFO - 'MOZ_NO_REMOTE': '1', 07:43:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:17 INFO - 'NO_EM_RESTART': '1', 07:43:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:17 INFO - 'PWD': '/builds/slave/test', 07:43:17 INFO - 'SHELL': '/bin/bash', 07:43:17 INFO - 'SHLVL': '1', 07:43:17 INFO - 'TERM': 'linux', 07:43:17 INFO - 'TMOUT': '86400', 07:43:17 INFO - 'USER': 'cltbld', 07:43:17 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:17 INFO - '_': '/tools/buildbot/bin/python'} 07:43:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:17 INFO - Downloading/unpacking blobuploader==1.2.4 07:43:17 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:43:17 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:43:17 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:43:17 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:43:17 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:43:17 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:43:19 INFO - Downloading blobuploader-1.2.4.tar.gz 07:43:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:43:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:43:19 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:43:19 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:43:19 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:43:19 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:43:19 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:43:19 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:43:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:43:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:43:20 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:43:20 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:43:20 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:43:20 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:43:20 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:43:20 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:43:20 INFO - Downloading docopt-0.6.1.tar.gz 07:43:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:43:20 INFO - Installing collected packages: blobuploader, requests, docopt 07:43:20 INFO - Running setup.py install for blobuploader 07:43:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:43:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:43:20 INFO - Running setup.py install for requests 07:43:20 INFO - Running setup.py install for docopt 07:43:20 INFO - Successfully installed blobuploader requests docopt 07:43:20 INFO - Cleaning up... 07:43:20 INFO - Return code: 0 07:43:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:20 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:20 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:43:20 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:43:20 INFO - Using env: {'DISPLAY': ':0', 07:43:20 INFO - 'HOME': '/home/cltbld', 07:43:20 INFO - 'LANG': 'en_US.UTF-8', 07:43:20 INFO - 'LANGUAGE': 'en_US:en', 07:43:20 INFO - 'LOGNAME': 'cltbld', 07:43:20 INFO - 'MAIL': '/var/mail/cltbld', 07:43:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:20 INFO - 'MOZ_NO_REMOTE': '1', 07:43:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:20 INFO - 'NO_EM_RESTART': '1', 07:43:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:20 INFO - 'PWD': '/builds/slave/test', 07:43:20 INFO - 'SHELL': '/bin/bash', 07:43:20 INFO - 'SHLVL': '1', 07:43:20 INFO - 'TERM': 'linux', 07:43:20 INFO - 'TMOUT': '86400', 07:43:20 INFO - 'USER': 'cltbld', 07:43:20 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:20 INFO - '_': '/tools/buildbot/bin/python'} 07:43:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:21 INFO - Running setup.py (path:/tmp/pip-XKX3Es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:21 INFO - Running setup.py (path:/tmp/pip-WC_wUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:21 INFO - Running setup.py (path:/tmp/pip-yzm5oo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:21 INFO - Running setup.py (path:/tmp/pip-wGnLVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:21 INFO - Running setup.py (path:/tmp/pip-kDDDT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:21 INFO - Running setup.py (path:/tmp/pip-fj0OAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:21 INFO - Running setup.py (path:/tmp/pip-dJ1nTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:21 INFO - Running setup.py (path:/tmp/pip-ZFiNYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:21 INFO - Running setup.py (path:/tmp/pip-HBv6Ad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:21 INFO - Running setup.py (path:/tmp/pip-JEwryQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:21 INFO - Running setup.py (path:/tmp/pip-OhTfrg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:21 INFO - Running setup.py (path:/tmp/pip-lRUEzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:21 INFO - Running setup.py (path:/tmp/pip-sSRO9b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:22 INFO - Running setup.py (path:/tmp/pip-heCiQL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:22 INFO - Running setup.py (path:/tmp/pip-X137em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:22 INFO - Running setup.py (path:/tmp/pip-XH4aBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:22 INFO - Running setup.py (path:/tmp/pip-LqrODc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:43:22 INFO - Running setup.py install for manifestparser 07:43:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:43:22 INFO - Running setup.py install for mozcrash 07:43:22 INFO - Running setup.py install for mozdebug 07:43:22 INFO - Running setup.py install for mozdevice 07:43:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:43:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:43:22 INFO - Running setup.py install for mozfile 07:43:22 INFO - Running setup.py install for mozhttpd 07:43:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:43:22 INFO - Running setup.py install for mozinfo 07:43:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Running setup.py install for mozInstall 07:43:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Running setup.py install for mozleak 07:43:23 INFO - Running setup.py install for mozlog 07:43:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Running setup.py install for moznetwork 07:43:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Running setup.py install for mozprocess 07:43:23 INFO - Running setup.py install for mozprofile 07:43:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Running setup.py install for mozrunner 07:43:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:43:23 INFO - Running setup.py install for mozscreenshot 07:43:23 INFO - Running setup.py install for moztest 07:43:24 INFO - Running setup.py install for mozversion 07:43:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:43:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:43:24 INFO - Cleaning up... 07:43:24 INFO - Return code: 0 07:43:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:24 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:24 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:43:24 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:43:24 INFO - Using env: {'DISPLAY': ':0', 07:43:24 INFO - 'HOME': '/home/cltbld', 07:43:24 INFO - 'LANG': 'en_US.UTF-8', 07:43:24 INFO - 'LANGUAGE': 'en_US:en', 07:43:24 INFO - 'LOGNAME': 'cltbld', 07:43:24 INFO - 'MAIL': '/var/mail/cltbld', 07:43:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:24 INFO - 'MOZ_NO_REMOTE': '1', 07:43:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:24 INFO - 'NO_EM_RESTART': '1', 07:43:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:24 INFO - 'PWD': '/builds/slave/test', 07:43:24 INFO - 'SHELL': '/bin/bash', 07:43:24 INFO - 'SHLVL': '1', 07:43:24 INFO - 'TERM': 'linux', 07:43:24 INFO - 'TMOUT': '86400', 07:43:24 INFO - 'USER': 'cltbld', 07:43:24 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:24 INFO - '_': '/tools/buildbot/bin/python'} 07:43:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:43:24 INFO - Running setup.py (path:/tmp/pip-0Fj69C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:43:24 INFO - Running setup.py (path:/tmp/pip-KRMA2r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:43:24 INFO - Running setup.py (path:/tmp/pip-oEHGJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:43:24 INFO - Running setup.py (path:/tmp/pip-_vX0DL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:43:24 INFO - Running setup.py (path:/tmp/pip-72uKxT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:43:24 INFO - Running setup.py (path:/tmp/pip-LCkj17-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:43:24 INFO - Running setup.py (path:/tmp/pip-vO8FW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:43:24 INFO - Running setup.py (path:/tmp/pip-O_mngr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:43:24 INFO - Running setup.py (path:/tmp/pip-bGHBkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:43:24 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:43:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:43:25 INFO - Running setup.py (path:/tmp/pip-iamFvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:43:25 INFO - Running setup.py (path:/tmp/pip-GL6Whz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:43:25 INFO - Running setup.py (path:/tmp/pip-VPyqk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:43:25 INFO - Running setup.py (path:/tmp/pip-EKrVlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:43:25 INFO - Running setup.py (path:/tmp/pip-Qji2Wd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:25 INFO - Running setup.py (path:/tmp/pip-EjEQl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:43:25 INFO - Running setup.py (path:/tmp/pip-ZqqjQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:43:25 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:43:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:43:25 INFO - Running setup.py (path:/tmp/pip-au6_4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:43:25 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:43:25 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:43:25 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:43:25 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:43:25 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:43:25 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:43:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:43:25 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:43:25 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:43:25 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:43:25 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:43:25 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:43:25 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:43:27 INFO - Downloading blessings-1.6.tar.gz 07:43:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:43:27 INFO - Installing collected packages: blessings 07:43:27 INFO - Running setup.py install for blessings 07:43:27 INFO - Successfully installed blessings 07:43:27 INFO - Cleaning up... 07:43:27 INFO - Return code: 0 07:43:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:43:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:27 INFO - Reading from file tmpfile_stdout 07:43:27 INFO - Current package versions: 07:43:27 INFO - argparse == 1.2.1 07:43:27 INFO - blessings == 1.6 07:43:27 INFO - blobuploader == 1.2.4 07:43:27 INFO - docopt == 0.6.1 07:43:27 INFO - functools32 == 3.2.3-2 07:43:27 INFO - jsonschema == 2.5.1 07:43:27 INFO - manifestparser == 1.1 07:43:27 INFO - mozInstall == 1.12 07:43:27 INFO - mozcrash == 0.17 07:43:27 INFO - mozdebug == 0.1 07:43:27 INFO - mozdevice == 0.48 07:43:27 INFO - mozfile == 1.2 07:43:27 INFO - mozhttpd == 0.7 07:43:27 INFO - mozinfo == 0.9 07:43:27 INFO - mozleak == 0.1 07:43:27 INFO - mozlog == 3.2 07:43:27 INFO - moznetwork == 0.27 07:43:27 INFO - mozprocess == 0.23 07:43:27 INFO - mozprofile == 0.28 07:43:27 INFO - mozrunner == 6.12 07:43:27 INFO - mozscreenshot == 0.1 07:43:27 INFO - mozsystemmonitor == 0.3 07:43:27 INFO - moztest == 0.7 07:43:27 INFO - mozversion == 1.4 07:43:27 INFO - psutil == 3.1.1 07:43:27 INFO - requests == 1.2.3 07:43:27 INFO - wsgiref == 0.1.2 07:43:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:43:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:27 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:27 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:43:27 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:43:27 INFO - Using env: {'DISPLAY': ':0', 07:43:27 INFO - 'HOME': '/home/cltbld', 07:43:27 INFO - 'LANG': 'en_US.UTF-8', 07:43:27 INFO - 'LANGUAGE': 'en_US:en', 07:43:27 INFO - 'LOGNAME': 'cltbld', 07:43:27 INFO - 'MAIL': '/var/mail/cltbld', 07:43:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:27 INFO - 'MOZ_NO_REMOTE': '1', 07:43:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:27 INFO - 'NO_EM_RESTART': '1', 07:43:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:27 INFO - 'PWD': '/builds/slave/test', 07:43:27 INFO - 'SHELL': '/bin/bash', 07:43:27 INFO - 'SHLVL': '1', 07:43:27 INFO - 'TERM': 'linux', 07:43:27 INFO - 'TMOUT': '86400', 07:43:27 INFO - 'USER': 'cltbld', 07:43:27 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:27 INFO - '_': '/tools/buildbot/bin/python'} 07:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 INFO - Cleaning up... 07:43:28 INFO - Return code: 0 07:43:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:43:28 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:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:28 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:43:28 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:43:28 INFO - Using env: {'DISPLAY': ':0', 07:43:28 INFO - 'HOME': '/home/cltbld', 07:43:28 INFO - 'LANG': 'en_US.UTF-8', 07:43:28 INFO - 'LANGUAGE': 'en_US:en', 07:43:28 INFO - 'LOGNAME': 'cltbld', 07:43:28 INFO - 'MAIL': '/var/mail/cltbld', 07:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:28 INFO - 'MOZ_NO_REMOTE': '1', 07:43:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:28 INFO - 'NO_EM_RESTART': '1', 07:43:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:28 INFO - 'PWD': '/builds/slave/test', 07:43:28 INFO - 'SHELL': '/bin/bash', 07:43:28 INFO - 'SHLVL': '1', 07:43:28 INFO - 'TERM': 'linux', 07:43:28 INFO - 'TMOUT': '86400', 07:43:28 INFO - 'USER': 'cltbld', 07:43:28 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:28 INFO - '_': '/tools/buildbot/bin/python'} 07:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:43:28 INFO - Cleaning up... 07:43:28 INFO - Return code: 0 07:43:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:43:28 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:43:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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:43:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:43:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:43:28 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 0x7ffedcfacf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18948a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19622e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', '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:43:28 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:43:28 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:43:28 INFO - Using env: {'DISPLAY': ':0', 07:43:28 INFO - 'HOME': '/home/cltbld', 07:43:28 INFO - 'LANG': 'en_US.UTF-8', 07:43:28 INFO - 'LANGUAGE': 'en_US:en', 07:43:28 INFO - 'LOGNAME': 'cltbld', 07:43:28 INFO - 'MAIL': '/var/mail/cltbld', 07:43:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:28 INFO - 'MOZ_NO_REMOTE': '1', 07:43:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:28 INFO - 'NO_EM_RESTART': '1', 07:43:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:28 INFO - 'PWD': '/builds/slave/test', 07:43:28 INFO - 'SHELL': '/bin/bash', 07:43:28 INFO - 'SHLVL': '1', 07:43:28 INFO - 'TERM': 'linux', 07:43:28 INFO - 'TMOUT': '86400', 07:43:28 INFO - 'USER': 'cltbld', 07:43:28 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:28 INFO - '_': '/tools/buildbot/bin/python'} 07:43:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:43:28 INFO - Downloading/unpacking flake8 07:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:28 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:43:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:43:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:43:30 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:43:30 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:43:30 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:43:30 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:43:30 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:43:30 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:43:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:43:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:43:30 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:43:30 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:43:30 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:43:30 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:43:30 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:43:30 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:43:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:43:30 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:43:30 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:43:30 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:43:30 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:43:30 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:43:30 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:43:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:43:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:43:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:43:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:43:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:43:31 INFO - no previously-included directories found matching 'docs/_build' 07:43:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:43:31 INFO - Running setup.py install for pep8 07:43:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:43:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:43:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:43:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:43:31 INFO - no previously-included directories found matching 'docs/_build' 07:43:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:43:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:43:31 INFO - Cleaning up... 07:43:31 INFO - Return code: 0 07:43:31 INFO - Running post-action listener: _resource_record_post_action 07:43:31 INFO - Running post-action listener: _start_resource_monitoring 07:43:31 INFO - Starting resource monitoring. 07:43:31 INFO - ##### 07:43:31 INFO - ##### Finished create-virtualenv step (success) 07:43:31 INFO - ##### 07:43:31 INFO - ##### 07:43:31 INFO - ##### Running install step. 07:43:31 INFO - ##### 07:43:31 INFO - Running pre-action listener: _resource_record_pre_action 07:43:31 INFO - Running main action method: install 07:43:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:43:31 INFO - Reading from file tmpfile_stdout 07:43:31 INFO - Detecting whether we're running mozinstall >=1.0... 07:43:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:43:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:43:31 INFO - Reading from file tmpfile_stdout 07:43:31 INFO - Output received: 07:43:31 INFO - Usage: mozinstall [options] installer 07:43:31 INFO - Options: 07:43:31 INFO - -h, --help show this help message and exit 07:43:31 INFO - -d DEST, --destination=DEST 07:43:31 INFO - Directory to install application into. [default: 07:43:31 INFO - "/builds/slave/test"] 07:43:31 INFO - --app=APP Application being installed. [default: firefox] 07:43:31 INFO - mkdir: /builds/slave/test/build/application 07:43:31 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:43:31 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:43:47 INFO - Reading from file tmpfile_stdout 07:43:47 INFO - Output received: 07:43:47 INFO - /builds/slave/test/build/application/firefox/firefox 07:43:47 INFO - Running post-action listener: _resource_record_post_action 07:43:47 INFO - ##### 07:43:47 INFO - ##### Finished install step (success) 07:43:47 INFO - ##### 07:43:47 INFO - ##### 07:43:47 INFO - ##### Running run-tests step. 07:43:47 INFO - ##### 07:43:47 INFO - Running pre-action listener: _resource_record_pre_action 07:43:47 INFO - Running main action method: run_tests 07:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:43:47 INFO - Python 2.7.3 07:43:47 INFO - Return code: 0 07:43:47 INFO - grabbing minidump binary from tooltool 07:43:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:43:47 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 0x195ce20>, '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 0x19603a0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1960830>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:43:47 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:43:47 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:43:47 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:43:47 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:43:47 INFO - Return code: 0 07:43:47 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 07:43:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:43:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:43:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 07:43:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:43:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:43:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:43:48 INFO - Return code: 0 07:43:48 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/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:43:48 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/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --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:43:48 INFO - Using env: {'DISPLAY': ':0', 07:43:48 INFO - 'HOME': '/home/cltbld', 07:43:48 INFO - 'LANG': 'en_US.UTF-8', 07:43:48 INFO - 'LANGUAGE': 'en_US:en', 07:43:48 INFO - 'LOGNAME': 'cltbld', 07:43:48 INFO - 'MAIL': '/var/mail/cltbld', 07:43:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:43:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 07:43:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:43:48 INFO - 'MOZ_NO_REMOTE': '1', 07:43:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:43:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 07:43:48 INFO - 'NO_EM_RESTART': '1', 07:43:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 07:43:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:43:48 INFO - 'PWD': '/builds/slave/test', 07:43:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:43:48 INFO - 'SHELL': '/bin/bash', 07:43:48 INFO - 'SHLVL': '1', 07:43:48 INFO - 'TERM': 'linux', 07:43:48 INFO - 'TMOUT': '86400', 07:43:48 INFO - 'USER': 'cltbld', 07:43:48 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437', 07:43:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:43:48 INFO - '_': '/tools/buildbot/bin/python'} 07:43:48 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/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--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:43:48 INFO - mozversion application_buildid: 20160721050633 07:43:48 INFO - mozversion application_changeset: 929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8 07:43:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:43:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:43:48 INFO - mozversion application_name: Firefox 07:43:48 INFO - mozversion application_remotingname: firefox-dev 07:43:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:43:48 INFO - mozversion application_vendor: Mozilla 07:43:48 INFO - mozversion application_version: 49.0a2 07:43:48 INFO - mozversion platform_buildid: 20160721050633 07:43:48 INFO - mozversion platform_changeset: 929a7823963114ca4072ebc5e2f0dd8b0b9bb7d8 07:43:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:43:48 INFO - mozversion platform_version: 49.0a2 07:43:48 INFO - using testdate: 1469112228 07:43:48 INFO - actual date: 1469112228 07:43:48 INFO - starting webserver on 'localhost:32916' 07:43:48 INFO - SUITE-START | Running 2 tests 07:43:48 INFO - TEST-START | dromaeo_css 07:43:48 INFO - operating with platform_type : linux_ 07:43:48 INFO - Initialising browser for dromaeo_css test... 07:43:48 INFO - TEST-INFO | started process 7218 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNp3tDv/profile http://localhost:32916/getInfo.html) 07:43:53 INFO - PROCESS | 7218 | __metrics Screen width/height:1600/1200 07:43:53 INFO - PROCESS | 7218 | colorDepth:24 07:43:53 INFO - PROCESS | 7218 | Browser inner width/height: 1024/697 07:43:53 INFO - PROCESS | 7218 | __metrics 07:43:54 INFO - PROCESS | 7218 | 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:43:54 ERROR - PROCESS | 7218 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:43:55 INFO - TEST-INFO | 7218: exit 0 07:43:55 INFO - Browser initialized. 07:43:55 INFO - Running cycle 1/1 for dromaeo_css test... 07:43:55 INFO - TEST-INFO | started process 7310 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNp3tDv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:06 INFO - PROCESS | 7310 | RSS: Main: 145125376 07:44:06 INFO - PROCESS | 7310 | 07:44:58 INFO - PROCESS | 7310 | 1469112298047 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:47:05 INFO - PROCESS | 7310 | Cycle 1(1): loaded http://localhost:32916/tests/dromaeo/cssquery-dojo.html (next: http://localhost:32916/tests/dromaeo/cssquery-ext.html) 07:47:06 INFO - PROCESS | 7310 | RSS: Main: 254685184 07:47:06 INFO - PROCESS | 7310 | 07:50:09 INFO - PROCESS | 7310 | Cycle 1(1): loaded http://localhost:32916/tests/dromaeo/cssquery-ext.html (next: http://localhost:32916/tests/dromaeo/cssquery-jquery.html) 07:50:09 INFO - PROCESS | 7310 | RSS: Main: 228675584 07:50:09 INFO - PROCESS | 7310 | 07:53:11 INFO - PROCESS | 7310 | Cycle 1(1): loaded http://localhost:32916/tests/dromaeo/cssquery-jquery.html (next: http://localhost:32916/tests/dromaeo/cssquery-mootools.html) 07:53:12 INFO - PROCESS | 7310 | RSS: Main: 226000896 07:53:12 INFO - PROCESS | 7310 | 07:56:14 INFO - PROCESS | 7310 | Cycle 1(1): loaded http://localhost:32916/tests/dromaeo/cssquery-mootools.html (next: http://localhost:32916/tests/dromaeo/cssquery-prototype.html) 07:56:15 INFO - PROCESS | 7310 | RSS: Main: 217968640 07:56:15 INFO - PROCESS | 7310 | 07:59:17 INFO - PROCESS | 7310 | Cycle 1(1): loaded http://localhost:32916/tests/dromaeo/cssquery-prototype.html (next: http://localhost:32916/tests/dromaeo/cssquery-yui.html) 07:59:18 INFO - PROCESS | 7310 | RSS: Main: 220430336 07:59:18 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | Cycle 1(1): loaded http://localhost:32916/tests/dromaeo/cssquery-yui.html (next: http://localhost:32916/tests/dromaeo/cssquery-dojo.html) 08:02:20 INFO - PROCESS | 7310 | RSS: Main: 257495040 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | __start_tp_report 08:02:20 INFO - PROCESS | 7310 | _x_x_mozilla_page_load 08:02:20 INFO - PROCESS | 7310 | _x_x_mozilla_page_load_details 08:02:20 INFO - PROCESS | 7310 | |i|pagename|runs| 08:02:20 INFO - PROCESS | 7310 | |0;dojo.html;3271.7219036381903;3303.702666759992;3328.467445208767;3343.0973637117977;3358.110100823281;5420.051490989076;5500.917486237709;5511.338639363282;5514.5864060195545;5534.806281780138;5453.64551304166;5495;5511;5513.338399392069;5546.168074788784;4848.151573474628;4966.776495057722;4989.251612258154;5022.899542009158;5055.064813009605;4802.183628783097;4926.113299606088;4950.232713929328;4957.330760347361;4957.727324997142;4925.75371231437;4984.800607975677;5017.67385119966;5033.094043072246;5046.21783623539;5102.158143906251;5103.209002604603;5103.2855400244;5116.411612664554;5145.202493613478;4859.0767754126955;4940.925886111711;4986.127427700174;5021.096202683552;5022.196448568212;5290.841274761725;5307.867303317448;5318.7074710891275;5348.786048558053;5350.571954243651;5822.184894114827;5854.443827836349;5896.587238893279;5898.7640494380175;5913.113033045035;5785.190073389729;5833.445822646841;5851.093080572518;5855.297364316267;5921.822345329647;5485.75314110862;5518.751656175482;5529.419410961871;5531.25328080708;5536.972315138479;5775.451332123442;5984.2818861736;5998.3701711320555;5999.520038396918;6007.15899774032;5618.91571626426;5662.462066103713;5687.03320435527;5746.511546518593;5754.05058165398;5843.328017277982;5847.298324201123;5851.561132915049;5860.033094539353;5867.530597552271;5629.8592535186945;5709.0865461525955;5790.88418231633;5803.071508558611;5804.680742559115;5724.914126288109;5823.272090988627;5825.679587622637;5828.009238429478;5868.941310586925;4490.550944905549;4501.977490112528;4504.48198457175;4504.527024662429;4527;3987.4018897165656;3989.162275922031;4033;4044.5753195914594;4080.7959602019782;5535.529479994165;5551.111822108443;5556.888862222814;5560.054790685593;5562.749676264578;5849.181114643953;5853.9170253503235;5857.7656893724625;5859.824205273849;5889.234399528034;5401.783928642811;5463.890722185534;5506.146547285097;5525.502704756591;5579.414161512983;4924.3105965164905;4956.083124621884;4960.801567937242;4966.453690094087;4974.17926042199;6052.54605904559;6112.633242005494;6114.2662880453745;6116.694165291718;6123.38766123393;5706.201131841629;5763.5965482417505;5818.563607729522;5824.534037276923;5862.501687356623;6119.663418511846;6120.846978825387;6127.632342059491;6137.294211165595;6141.600795948428;5598.804041858515;5763.6541807491685;5766.135079738073;5772.826815195551;5777.768889244383;5769.59612827098;5778.653280802997;5833.854153646193;5865.706714664335;5874.500667443315;5670.432956704297;5701.486866182064;5704.172894930295;5706.372299047019;5720.628159169807;5425.620206585501;5625.353084395347;5700.515456186271;5717.056685646821;5719.8856022880145;5561.221429000024;5655.066913959151;5657.15142728594;5661.433856614306;5691.402402747817;5773.364929857797;5800.216970709069;5819.301683797971;5822.417758224144;5826.883462330815;5503.477169668875;5570.275864137799;5593.916091258553;5627.184058311604;5670.943290567042 08:02:20 INFO - PROCESS | 7310 | |1;ext.html;605.7092595554134;646.5700309294319;650.1775254303307;662.2417332154681;664.4352300544534;33120.5031924521;36930.44604330934;46850.82872928174;47739;47774;49538;49893;49933.25100123501;50057.74971125148;50113;8082.43422960393;9171.174594286513;9318.394304370195;9319.580618872167;10942.726431839228;10647.254692171551;11059.668209953714;11062.502187401546;11063.446827658625;11064.944675276653;11153.776924461506;11183.44082795861;11205.495752691148;11207.831882521768;11215.38315392653;8285.917140828606;8419.494830310201;8449.619767110495;11060.557577696882;11071.16966227528;10689.572417103307;10775.299605525615;11124.555017799279;11139.387333696643;11144.665660030212;46084.53915460836;46584.76707616474;46715.29927051097;46742.065158696976;46757.53242467566;3586.1572530455323;3705.962940370616;3712.276106159273;3720.5163328767358;3851.1912498375373;35046.47430288548;35263.82368088169;35730.46404303938;35946.460803087975;35974;4694.305293356913;4802.30366596845;4867.172580661296;4883.536064073907;4963.007398520311;388603;389739;391685;392193;393122.99999999715;39224.60775392267;70247.6487617569;70782.64608676924;70822.2917770826;70824;39646.603533964866;42037.78981105136;42052.57947420548;42066;42069.36895946563;17757.200925958103;18039.819601804076;18051;18057.819421805612;18059.819401806075;12344.3827808611;14238.216898070705;14520.927395363167;14640.341184646504;15060.849391505944;9398.812023759487;9540.570674319672;9543.856842147374;9552.808943821085;9616.95191524038;3637.836297366625;3662.853485860589;3679;3681.7606855554304;3694.833732482045;1056;1086.8913108689226;1104.4643347976225;1201.0751721174647;1233.1368042370198;34281.82859085689;34691.30617387638;34694;35008.31581102845;35074;1147.9081673466103;1211.5214490276292;1214.8724383939737;1257.9874201258053;1290.8192853000587;18057.548561286072;18202.544936376697;18311.63376732458;18356.816431835778;18392.356267530573;609359;614093;614788;617049;619694;43874.561254387045;44248.55751442444;44514;44537.55462445334;44658.77670611626;1357.429879450633;1373.0800363756307;1421.736978658972;1472.0063956829097;1477.416420513891;31116.510679146068;35033.29933401369;35036.35913925303;35076.82461587676;35722;56973.43026569847;57332.1400179006;57573.4242657568;57622.42377576171;57698.134527982955;410268;411026;411806;412443;412894;31044.844775775975;31601.683983160794;40483;47074;48186.27720584124;52629.7368513155;52807;53195.73402132965;53256.73371633117;53575.464245358606;43707.12585748204;44224;44420.33369499525;44916.77541612271;45127.09745805;43246.56753432551;43836.780816095714;43893.561064390226;44325.55674443214;44692.32961505645;44302.556974431136;44323.33514997213;44388.556114439736;45138.322925156805;46101;37489.812550938164;40124.1975160501;40511.18977620372;40846.79576602098;41193.794031029654;30016.549751754177;30188.6980581793;30193.849030754707;30694.539581906734;30708.618112185453 08:02:20 INFO - PROCESS | 7310 | |2;jquery.html;413.69179960929114;419.2704693832732;421.4016097142058;422.2041451863238;429.5811583705887;19117.330893418715;19970.753083154064;20469;20625.896870515626;20805.16779328827;19491.902540487314;19793.307234246804;20047.59904801903;20631.1747530099;20708.585828283463;5668.744906479218;5846.532277417818;5896.705164741746;5901.793437229698;5903.203067585864;4117.300058989979;4253.8723838284905;5800.332961709394;5850.268716410449;6476.643784591869;6537.967310163443;6549.083128362033;6556.114924485181;6570.605763654172;6573.046908198308;6391.904121438136;6461.095446637474;6541.607503549779;6553.574017688835;6561.409473147416;3903.394973779069;5461.317335333083;5797.652140871562;5804.535637149018;5810.622309549865;23861.642075368884;24150.758492415025;24171.758282417126;24175;24369.756302436926;2294.300238427279;2436.1838784007377;2566.897324107052;2689.38144226827;3047.040182342555;10355.5340009699;10718.892811071868;10991.670249892515;12744.6813829655;14698.926505367512;1883.52911772057;1890.1683259365973;2011.6077364913847;2071.8238949689294;2138.9786102138937;81617;84123.57938210331;84354.99999999939;84960.57519712424;85759;11459.656210313704;11509.25189862673;11527.193096483334;11586.88413115858;11816.88183118175;16230.675386492205;16283.83716162847;16344.283850195558;16349.346026159059;16378;8263.132371101068;8430.199131082538;8529.82940341202;8597.52713600758;9186.86219706705;8785;8878.644854205888;8879.33404994628;8940.418872773249;8974.28205743539;4937.08663897173;4969.229769385751;4989.201727723546;5003.54968052877;5035.7985680572365;1984.1666500069716;2000.5598768270956;2023.6357455657828;2080.157536197842;2125.968110478344;775.5463054113407;885.8051228729669;919.3564504846634;1019.2327829941744;1076.4025965589017;19649.803501964798;19702.40892773219;19917.900410498143;19938.80875749975;20017.399478015683;1068.8824229334766;1081.5403453532217;1088.7768007558432;1096.4353358020626;1147.9540818367173;10004.49977501122;11106.16703747206;11121.777564448666;11250.774984500264;11301.886981130083;454886;456844;466202;499918;517791;97845;98225.50887245518;99710.50144749375;100393.49803251083;101230.49384753029;1067.1836045425257;1110.5502271580024;1126.5437497813398;1130.711668524521;1131.5586921100942;18010.189541470405;18554.907225464325;18630.627387451903;18684;18953.620927581094;42409.151816964106;42583.787081064394;42962.78518607387;43639.732618722766;44298.335524966504;58719.4128058714;60651.393486064575;61912.69043654753;62065.06902396378;62434.68782656058;8842.336824738042;8896.466212027299;8928;8930.508822014592;9110.453372797654;9856.605735770489;11251.831222531504;13270.1374410663;13488.460461581426;14245.358958847084;11646.88353116458;11840.940795295968;12135.817962730389;12431.191972521758;12638.494460221857;11113.277636953571;11573.13201509907;11637.941810290895;11714.062874969812;11827.704307392383;11928.659975206956;11978.880211197777;12099.213551119148;12218.75562488763;12220.205686630341;8609.311255099452;8807.383483156118;8876.556172191236;9037.141471560191;9041.59312830911;10175.389476631426;12503.187292825936;12518.499260029497;12553.49786008586;12642.98856091492 08:02:20 INFO - PROCESS | 7310 | |3;mootools.html;1202.488942199566;1524.1160127126263;1570.0108931373236;1751.2994802079168;1862.0317434933843;8010.319122874555;9177.08229177082;9511.857322140173;9514.191293740023;9749;9452.432854028762;9602.423854568731;9620.518974051305;9628.13346798788;9632;2156.1914282144194;2214.7563767985507;2400.2079313826393;2460.680111585496;2498.1756020513276;2507.749225077496;2545.9745402546014;2550.2731721459386;2550.5409026375246;2552.8213025088253;2421.225207933462;2471.270975062361;2476.2571228631336;2477.6531285620026;2494.3514686181543;2010.6079314533672;2311.15642790382;2379.95240095198;2398.4243781492314;2411.8191135664806;2448.4123810285582;2455.6439316299216;2473.356927198923;2475.789557887582;2480.541099896525;9573.138417542385;9652.613895444174;9655.372400793925;9677.177439917616;9679.467629280387;5526.50261476469;5679.318481782171;5689.9146512802345;5699.088145896637;5701.971490142564;3594.388953877847;3633.3096711624707;3685.594584595692;3707.3141468828085;3710.517632707758;1224.5591587028662;1234.9567765128268;1313.6256166992457;1333.4999375234288;1370;13315.40080696371;13415.86584134156;13425.731485370336;13446.93276533611;13477.595672129852;4601.8849528761375;4692.41344831896;4734.4081989751285;4746.121967436035;4752.120857641347;10462.947685261677;10484.423356715453;10512.842307365396;10516.737081572868;10517;5508.586855985737;5509.834704958857;5515.062439385314;5516.641418307797;5519.39286678465;4521.570450807168;5049.419316778544;5178.844634660966;5188.195829646411;5216.634835561475;3984.143409167043;3995.900102497461;4081.959180408158;4111.6299533042165;4121.670266378683;1163.9979235714557;1164.9242799218023;1172.8358029875824;1173.2256710570982;1176.2707121584672;951.6003278622995;953.1469334945185;1038.7610849504656;1039.589362201926;1040.3029969920171;6417.711202995783;6451.741930322828;6556.344365563501;6595.538312318187;6599.538032337787;459.54275495881143;460.62689221730506;466.55210997442157;1101.146611376174;1131.083822104082;4279.700420970502;4479.10417916418;4512.34570987205;4527.864164075083;4558.635309175258;11702.531898724126;12049.216800908089;12058.457369418396;12063.155579109378;12079.3356365399;11194.160437967184;11670.358130302915;11691.064714822794;11974.700632484084;11986.700332491582;8788.472691638393;8982.955085224532;8999.77500562478;9001.819963600692;9003.684871029482;6410.391012853772;6664.866702666017;6762.560433571802;6834.214065382345;6851.794446166623;10663.200259980378;10768.138548915911;11001.559937602406;11015.83476247841;11045;10906.781864362827;11065.557377704801;11081.058110060736;11172.88827111751;11413.60052398162;7291.4166866649475;8455.450395724258;8588.570571471277;8673.91326086731;9024.142706442877;9063.274938004812;9357.157855793012;9372.718818435458;9391.107844754737;9437.858432123387;8234.752957411287;8343.374246931626;8399.748007559783;8414.158584141536;8543.10297418787;8159.877601836096;8193.877091843746;8285.917140828515;8402.663893444194;8407.70573029941;7185.53294035886;7266.96366518164;7307.378872795659;7322.194558598443;7353.889691654523;5284.841454756363;5390.892182156414;5420.891582168256;5443.292371991616;5581.5813813963305;5624.240727501735;5631.211630371669;5736.942630573641;5758.971205143947;5777.133429985536 08:02:20 INFO - PROCESS | 7310 | |4;prototype.html;334.82756380464065;342.4469481786914;355.236242079529;355.53069947669053;355.7740834570048;5286.31277933868;5413.648112872661;5496.697681627509;5528.364238112618;5547.972260138698;5294.311739473863;5382.273393091931;5387.568994480442;5407.270018547495;5407.5133238008575;4297.441332626754;4300.311950087978;4303;4311.417958575599;4315.115401342718;4070.3690927906223;4181.289180839264;4294.098239369736;4303.956960430387;4314.956850431503;4267.871963841089;4308.913821723564;4326.675499337546;4337.611964171467;4345.739255644656;4263.552327005682;4287.856071964018;4296.785160741966;4307.310830267143;4311.61195492404;3722.758020728644;3842.634949679776;3864.3237433449317;3865.357223180137;3878.7090968177217;5157.71632560209;5214.661047031968;5219;5233.293505376763;5254.00173966945;4866.489018653061;4876.195807551228;4878.341423907798;4885.633577481668;4889.8777530561665;5346.973265133688;5660;5668.348139963916;5709.943660422794;5735.0214176109175;4196.769177695225;4222.831086756526;4242.91514169718;4244.790234783072;4252.978735106305;5892.322382925976;5939.9109013364405;5954.4938680212235;5967;5977.622643206574;4205.306124489486;4218.620324170839;4231.640310573574;4286.442762440863;4309.827606895752;4246.893827654271;4258.616724494748;4262.211490874198;4282.379055037002;4297.720648157922;4797.7601119943865;4816.975915120402;4841.540053694894;4858.878528036827;4885.340479035356;5563.972180139073;5674.659520428788;5730.684812335278;5735.483806457439;5785.334686511021;1406.0438901547045;1477.6479521237948;1527.3279756906925;1530.8239552451387;1537.269796846489;971.9757006074905;989.2234595842373;1001.0940099210227;1001.1440218613127;1018.4500369800373;4926.531979461945;4937.530934561211;4972.65191436596;4991.3511243538105;5061.342025536656;5167.382832017603;5175.327207463006;5215.217717342429;5222.224443689164;5264.894702105937;4570.0611956691955;4922.704637721748;4925.876853078702;4944.579710724557;4980.77586508608;2764.847933363684;2765.1151631477733;2783.900359358083;2786.247713117447;2794.636697229347;5318.521333080042;5357.35711714589;5375.5162035416215;5421.2681288025615;5423.647462914898;5298.443663415286;5333.493318134771;5618.747156377891;6047.606905551125;6061.939380606225;6139.969300153471;6185.165002724664;6195.721192546346;6216.347283535164;6236.18929539157;5634.464490978994;5790.033826828636;5914.467697907209;5929.347771745191;5938.524918006463;5814.5639077068545;5825.679362763353;5826.854328641818;5851.707414629166;5889.970550147222;6168.845778855564;6169.629822210682;6175.84560385994;6184.288806787277;6229.750809967551;4111.9177616448105;4201.768902710258;4211.599898009684;4223.796218077833;4241.724287921275;5707.915496055736;5822.330432000204;5897.9115313271195;5921.407859214217;5960.168919763251;5628.9305032043785;5692.003899317686;5710.600257981901;5724.597473618824;5763.279590051244;5619.831405057855;5732.914006289992;5767.134929760569;5772.095208581242;5788.989725342883;5456.863578410572;5663.292088488939;5669.291338582677;5670.036093863971;5674.347450043298;5704.80033198836;5739.971300143473;5766.308043034863;5773.3072031356505;5790.1893734877995;5466.972665136808;5652.63257888236;5658.151277308437;5701.942980570141;5726.3944905067265 08:02:20 INFO - PROCESS | 7310 | |5;yui.html;780.6643143448315;793.7102957420543;820.9548474833882;860.8536548786706;884.5886662701845;15762.76355854663;16343.673126537462;16574;16606;16617.16914154292;15418.460353887589;15661.216939153053;15687.60780980476;15721.606959825982;15737.291821868015;873.3755364914085;882.4440602420466;887.4320434921656;889.1819526036062;891.754767438953;884.1335491218578;884.995575022124;885.6545947080643;886.2998231397188;886.4459712679575;932.9626814927428;933.6872147830485;936.5317341329335;937.6296362936636;937.6718148647986;927.8051609162084;927.9814403711922;928.7120992492349;929.330881765128;929.9628014879397;682.747383468115;683.8803209438378;685.2120061928772;685.3044160177424;685.5372623479132;14340.139591624431;14402.135871847722;14455.20496372699;14456.349464274133;14470.49353272641;12995.480180792756;13796.862031379758;14142.434302627882;14471.42114315426;14547.199904005276;11909.166358354918;11929.821052684216;12199;12387.132900696955;12623.368831558477;4005.959940400588;4048.655864251513;4090.672746180328;4096.09885825121;4183.184279065584;77082;77157.61421192858;77656.22343776604;77934.61032694801;78625;11740.119491038036;11887.881121188677;11988.940055299841;12146.878531214576;12210.816837747441;20019.29932452357;20049.799502005084;20064.799352006292;20105.29631462921;20108.798912010694;8858.069902660256;8930.419522731001;8943.418677785923;9042.728718138467;9157;4926.162552366106;4999.800007999639;5017.498250174954;5078.568321692697;5153.484651534892;3380.695737383647;3453.4819777033144;3465.8440370183403;3465.878694245689;3469.7918124912585;651.7979426377838;659.3768888400416;663.5056882622478;664.1299897134769;671.0370618162951;655.9344065593498;700.3066963705894;754.1930134755868;764.3503022430889;783.5847001089431;10576.629817956335;10742.94628526868;10766.56933722658;10810.621628242974;10826.18803589734;715.8174665460274;746.7199800074972;750.3846845586676;759.4721668440492;767.8310771630232;4862.197737373364;4890.437599676012;4953.578945789648;4967.726775027409;4987.0524600325825;116759;116899.41550292364;117627;120063;123728;78896;98711;101314;102775.48612256891;102833;83648.58175709083;85783.57108214544;89876.5506172465;90253.09746902446;92189;10523.263371563917;10675.410621509307;10881.292715973437;11028.338299702044;11191.384473854016;50864.23703644522;52283;52552;52783.47216527785;54322.18516722332;76691.61654191693;79815.60092199502;80635;81207.59396202982;81922.59038705006;12714.61856144317;13299.401526931504;13409.32953352348;13578.852574021763;13706.588802335946;15248.847511524742;15322.693546129238;15497.07017578949;15512.146831924352;15592.844071559139;14800.7779883304;14864.182469964257;14984.325705343068;15295.158766267961;15500;13767.793483097561;14700.926495367456;14812.259387030821;14889.404423823358;14994.100353978796;14548.345324460637;14629.561113166623;14636.341364638403;14804;15245.237738112828;12032.518699251932;12059.57791477294;12181.512739490321;12381.876181238433;12514.374281286082;12230.893903503304;12379.69050773738;12464.586424010093;12465;12537.686557835763 08:02:20 INFO - PROCESS | 7310 | __end_tp_report 08:02:20 INFO - PROCESS | 7310 | __start_cc_report 08:02:20 INFO - PROCESS | 7310 | _x_x_mozilla_cycle_collect,873 08:02:20 INFO - PROCESS | 7310 | __end_cc_report 08:02:20 INFO - PROCESS | 7310 | __startTimestamp1469113340907__endTimestamp 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | ------- Summary: start ------- 08:02:20 INFO - PROCESS | 7310 | Number of tests: 6 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | [#0] dojo.html Cycles:165 Average:5433.45 Median:5625.35 stddev:594.04 (10.6%) stddev-sans-first:571.14 08:02:20 INFO - PROCESS | 7310 | Values: 3271.7 3303.7 3328.5 3343.1 3358.1 5420.1 5500.9 5511.3 5514.6 5534.8 5453.6 5495.0 5511.0 5513.3 5546.2 4848.2 4966.8 4989.3 5022.9 5055.1 4802.2 4926.1 4950.2 4957.3 4957.7 4925.8 4984.8 5017.7 5033.1 5046.2 5102.2 5103.2 5103.3 5116.4 5145.2 4859.1 4940.9 4986.1 5021.1 5022.2 5290.8 5307.9 5318.7 5348.8 5350.6 5822.2 5854.4 5896.6 5898.8 5913.1 5785.2 5833.4 5851.1 5855.3 5921.8 5485.8 5518.8 5529.4 5531.3 5537.0 5775.5 5984.3 5998.4 5999.5 6007.2 5618.9 5662.5 5687.0 5746.5 5754.1 5843.3 5847.3 5851.6 5860.0 5867.5 5629.9 5709.1 5790.9 5803.1 5804.7 5724.9 5823.3 5825.7 5828.0 5868.9 4490.6 4502.0 4504.5 4504.5 4527.0 3987.4 3989.2 4033.0 4044.6 4080.8 5535.5 5551.1 5556.9 5560.1 5562.7 5849.2 5853.9 5857.8 5859.8 5889.2 5401.8 5463.9 5506.1 5525.5 5579.4 4924.3 4956.1 4960.8 4966.5 4974.2 6052.5 6112.6 6114.3 6116.7 6123.4 5706.2 5763.6 5818.6 5824.5 5862.5 6119.7 6120.8 6127.6 6137.3 6141.6 5598.8 5763.7 5766.1 5772.8 5777.8 5769.6 5778.7 5833.9 5865.7 5874.5 5670.4 5701.5 5704.2 5706.4 5720.6 5425.6 5625.4 5700.5 5717.1 5719.9 5561.2 5655.1 5657.2 5661.4 5691.4 5773.4 5800.2 5819.3 5822.4 5826.9 5503.5 5570.3 5593.9 5627.2 5670.9 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | [#1] ext.html Cycles:180 Average:63730.26 Median:40303.60 stddev:128415.12 (318.6%) stddev-sans-first:128687.90 08:02:20 INFO - PROCESS | 7310 | Values: 605.7 646.6 650.2 662.2 664.4 33120.5 36930.4 46850.8 47739.0 47774.0 49538.0 49893.0 49933.3 50057.7 50113.0 8082.4 9171.2 9318.4 9319.6 10942.7 10647.3 11059.7 11062.5 11063.4 11064.9 11153.8 11183.4 11205.5 11207.8 11215.4 8285.9 8419.5 8449.6 11060.6 11071.2 10689.6 10775.3 11124.6 11139.4 11144.7 46084.5 46584.8 46715.3 46742.1 46757.5 3586.2 3706.0 3712.3 3720.5 3851.2 35046.5 35263.8 35730.5 35946.5 35974.0 4694.3 4802.3 4867.2 4883.5 4963.0 388603.0 389739.0 391685.0 392193.0 393123.0 39224.6 70247.6 70782.6 70822.3 70824.0 39646.6 42037.8 42052.6 42066.0 42069.4 17757.2 18039.8 18051.0 18057.8 18059.8 12344.4 14238.2 14520.9 14640.3 15060.8 9398.8 9540.6 9543.9 9552.8 9617.0 3637.8 3662.9 3679.0 3681.8 3694.8 1056.0 1086.9 1104.5 1201.1 1233.1 34281.8 34691.3 34694.0 35008.3 35074.0 1147.9 1211.5 1214.9 1258.0 1290.8 18057.5 18202.5 18311.6 18356.8 18392.4 609359.0 614093.0 614788.0 617049.0 619694.0 43874.6 44248.6 44514.0 44537.6 44658.8 1357.4 1373.1 1421.7 1472.0 1477.4 31116.5 35033.3 35036.4 35076.8 35722.0 56973.4 57332.1 57573.4 57622.4 57698.1 410268.0 411026.0 411806.0 412443.0 412894.0 31044.8 31601.7 40483.0 47074.0 48186.3 52629.7 52807.0 53195.7 53256.7 53575.5 43707.1 44224.0 44420.3 44916.8 45127.1 43246.6 43836.8 43893.6 44325.6 44692.3 44302.6 44323.3 44388.6 45138.3 46101.0 37489.8 40124.2 40511.2 40846.8 41193.8 30016.5 30188.7 30193.8 30694.5 30708.6 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | [#2] jquery.html Cycles:180 Average:29475.70 Median:24161.26 stddev:79379.12 (328.5%) stddev-sans-first:79571.80 08:02:20 INFO - PROCESS | 7310 | Values: 413.7 419.3 421.4 422.2 429.6 19117.3 19970.8 20469.0 20625.9 20805.2 19491.9 19793.3 20047.6 20631.2 20708.6 5668.7 5846.5 5896.7 5901.8 5903.2 4117.3 4253.9 5800.3 5850.3 6476.6 6538.0 6549.1 6556.1 6570.6 6573.0 6391.9 6461.1 6541.6 6553.6 6561.4 3903.4 5461.3 5797.7 5804.5 5810.6 23861.6 24150.8 24171.8 24175.0 24369.8 2294.3 2436.2 2566.9 2689.4 3047.0 10355.5 10718.9 10991.7 12744.7 14698.9 1883.5 1890.2 2011.6 2071.8 2139.0 81617.0 84123.6 84355.0 84960.6 85759.0 11459.7 11509.3 11527.2 11586.9 11816.9 16230.7 16283.8 16344.3 16349.3 16378.0 8263.1 8430.2 8529.8 8597.5 9186.9 8785.0 8878.6 8879.3 8940.4 8974.3 4937.1 4969.2 4989.2 5003.5 5035.8 1984.2 2000.6 2023.6 2080.2 2126.0 775.5 885.8 919.4 1019.2 1076.4 19649.8 19702.4 19917.9 19938.8 20017.4 1068.9 1081.5 1088.8 1096.4 1148.0 10004.5 11106.2 11121.8 11250.8 11301.9 454886.0 456844.0 466202.0 499918.0 517791.0 97845.0 98225.5 99710.5 100393.5 101230.5 1067.2 1110.6 1126.5 1130.7 1131.6 18010.2 18554.9 18630.6 18684.0 18953.6 42409.2 42583.8 42962.8 43639.7 44298.3 58719.4 60651.4 61912.7 62065.1 62434.7 8842.3 8896.5 8928.0 8930.5 9110.5 9856.6 11251.8 13270.1 13488.5 14245.4 11646.9 11840.9 12135.8 12431.2 12638.5 11113.3 11573.1 11637.9 11714.1 11827.7 11928.7 11978.9 12099.2 12218.8 12220.2 8609.3 8807.4 8876.6 9037.1 9041.6 10175.4 12503.2 12518.5 12553.5 12643.0 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | [#3] mootools.html Cycles:180 Average:6224.44 Median:2509.09 stddev:3605.46 (143.7%) stddev-sans-first:3595.82 08:02:20 INFO - PROCESS | 7310 | Values: 1202.5 1524.1 1570.0 1751.3 1862.0 8010.3 9177.1 9511.9 9514.2 9749.0 9452.4 9602.4 9620.5 9628.1 9632.0 2156.2 2214.8 2400.2 2460.7 2498.2 2507.7 2546.0 2550.3 2550.5 2552.8 2421.2 2471.3 2476.3 2477.7 2494.4 2010.6 2311.2 2380.0 2398.4 2411.8 2448.4 2455.6 2473.4 2475.8 2480.5 9573.1 9652.6 9655.4 9677.2 9679.5 5526.5 5679.3 5689.9 5699.1 5702.0 3594.4 3633.3 3685.6 3707.3 3710.5 1224.6 1235.0 1313.6 1333.5 1370.0 13315.4 13415.9 13425.7 13446.9 13477.6 4601.9 4692.4 4734.4 4746.1 4752.1 10462.9 10484.4 10512.8 10516.7 10517.0 5508.6 5509.8 5515.1 5516.6 5519.4 4521.6 5049.4 5178.8 5188.2 5216.6 3984.1 3995.9 4082.0 4111.6 4121.7 1164.0 1164.9 1172.8 1173.2 1176.3 951.6 953.1 1038.8 1039.6 1040.3 6417.7 6451.7 6556.3 6595.5 6599.5 459.5 460.6 466.6 1101.1 1131.1 4279.7 4479.1 4512.3 4527.9 4558.6 11702.5 12049.2 12058.5 12063.2 12079.3 11194.2 11670.4 11691.1 11974.7 11986.7 8788.5 8983.0 8999.8 9001.8 9003.7 6410.4 6664.9 6762.6 6834.2 6851.8 10663.2 10768.1 11001.6 11015.8 11045.0 10906.8 11065.6 11081.1 11172.9 11413.6 7291.4 8455.5 8588.6 8673.9 9024.1 9063.3 9357.2 9372.7 9391.1 9437.9 8234.8 8343.4 8399.7 8414.2 8543.1 8159.9 8193.9 8285.9 8402.7 8407.7 7185.5 7267.0 7307.4 7322.2 7353.9 5284.8 5390.9 5420.9 5443.3 5581.6 5624.2 5631.2 5736.9 5759.0 5777.1 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | [#4] prototype.html Cycles:180 Average:4752.31 Median:5243.65 stddev:1387.29 (26.5%) stddev-sans-first:1350.98 08:02:20 INFO - PROCESS | 7310 | Values: 334.8 342.4 355.2 355.5 355.8 5286.3 5413.6 5496.7 5528.4 5548.0 5294.3 5382.3 5387.6 5407.3 5407.5 4297.4 4300.3 4303.0 4311.4 4315.1 4070.4 4181.3 4294.1 4304.0 4315.0 4267.9 4308.9 4326.7 4337.6 4345.7 4263.6 4287.9 4296.8 4307.3 4311.6 3722.8 3842.6 3864.3 3865.4 3878.7 5157.7 5214.7 5219.0 5233.3 5254.0 4866.5 4876.2 4878.3 4885.6 4889.9 5347.0 5660.0 5668.3 5709.9 5735.0 4196.8 4222.8 4242.9 4244.8 4253.0 5892.3 5939.9 5954.5 5967.0 5977.6 4205.3 4218.6 4231.6 4286.4 4309.8 4246.9 4258.6 4262.2 4282.4 4297.7 4797.8 4817.0 4841.5 4858.9 4885.3 5564.0 5674.7 5730.7 5735.5 5785.3 1406.0 1477.6 1527.3 1530.8 1537.3 972.0 989.2 1001.1 1001.1 1018.5 4926.5 4937.5 4972.7 4991.4 5061.3 5167.4 5175.3 5215.2 5222.2 5264.9 4570.1 4922.7 4925.9 4944.6 4980.8 2764.8 2765.1 2783.9 2786.2 2794.6 5318.5 5357.4 5375.5 5421.3 5423.6 5298.4 5333.5 5618.7 6047.6 6061.9 6140.0 6185.2 6195.7 6216.3 6236.2 5634.5 5790.0 5914.5 5929.3 5938.5 5814.6 5825.7 5826.9 5851.7 5890.0 6168.8 6169.6 6175.8 6184.3 6229.8 4111.9 4201.8 4211.6 4223.8 4241.7 5707.9 5822.3 5897.9 5921.4 5960.2 5628.9 5692.0 5710.6 5724.6 5763.3 5619.8 5732.9 5767.1 5772.1 5789.0 5456.9 5663.3 5669.3 5670.0 5674.3 5704.8 5740.0 5766.3 5773.3 5790.2 5467.0 5652.6 5658.2 5701.9 5726.4 08:02:20 INFO - PROCESS | 7310 | 08:02:20 INFO - PROCESS | 7310 | [#5] yui.html Cycles:180 Average:21460.93 Median:3465.86 stddev:30583.30 (882.4%) stddev-sans-first:30629.68 08:02:20 INFO - PROCESS | 7310 | Values: 780.7 793.7 821.0 860.9 884.6 15762.8 16343.7 16574.0 16606.0 16617.2 15418.5 15661.2 15687.6 15721.6 15737.3 873.4 882.4 887.4 889.2 891.8 884.1 885.0 885.7 886.3 886.4 933.0 933.7 936.5 937.6 937.7 927.8 928.0 928.7 929.3 930.0 682.7 683.9 685.2 685.3 685.5 14340.1 14402.1 14455.2 14456.3 14470.5 12995.5 13796.9 14142.4 14471.4 14547.2 11909.2 11929.8 12199.0 12387.1 12623.4 4006.0 4048.7 4090.7 4096.1 4183.2 77082.0 77157.6 77656.2 77934.6 78625.0 11740.1 11887.9 11988.9 12146.9 12210.8 20019.3 20049.8 20064.8 20105.3 20108.8 8858.1 8930.4 8943.4 9042.7 9157.0 4926.2 4999.8 5017.5 5078.6 5153.5 3380.7 3453.5 3465.8 3465.9 3469.8 651.8 659.4 663.5 664.1 671.0 655.9 700.3 754.2 764.4 783.6 10576.6 10742.9 10766.6 10810.6 10826.2 715.8 746.7 750.4 759.5 767.8 4862.2 4890.4 4953.6 4967.7 4987.1 116759.0 116899.4 117627.0 120063.0 123728.0 78896.0 98711.0 101314.0 102775.5 102833.0 83648.6 85783.6 89876.6 90253.1 92189.0 10523.3 10675.4 10881.3 11028.3 11191.4 50864.2 52283.0 52552.0 52783.5 54322.2 76691.6 79815.6 80635.0 81207.6 81922.6 12714.6 13299.4 13409.3 13578.9 13706.6 15248.8 15322.7 15497.1 15512.1 15592.8 14800.8 14864.2 14984.3 15295.2 15500.0 13767.8 14700.9 14812.3 14889.4 14994.1 14548.3 14629.6 14636.3 14804.0 15245.2 12032.5 12059.6 12181.5 12381.9 12514.4 12230.9 12379.7 12464.6 12465.0 12537.7 08:02:20 INFO - PROCESS | 7310 | -------- Summary: end -------- 08:02:20 INFO - PROCESS | 7310 | 08:02:21 INFO - TEST-INFO | 7310: exit 0 08:02:21 INFO - TEST-OK | dromaeo_css | took 1112778ms 08:02:21 INFO - TEST-START | kraken 08:02:21 INFO - operating with platform_type : linux_ 08:02:21 INFO - Initialising browser for kraken test... 08:02:21 INFO - TEST-INFO | started process 7507 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3TWvBs/profile http://localhost:32916/getInfo.html) 08:02:25 INFO - PROCESS | 7507 | __metrics Screen width/height:1600/1200 08:02:25 INFO - PROCESS | 7507 | colorDepth:24 08:02:25 INFO - PROCESS | 7507 | Browser inner width/height: 1024/697 08:02:25 INFO - PROCESS | 7507 | __metrics 08:02:26 INFO - PROCESS | 7507 | 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" 08:02:26 ERROR - PROCESS | 7507 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:02:27 INFO - TEST-INFO | 7507: exit 0 08:02:27 INFO - Browser initialized. 08:02:27 INFO - Running cycle 1/1 for kraken test... 08:02:27 INFO - TEST-INFO | started process 7595 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp3TWvBs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:02:39 INFO - PROCESS | 7595 | RSS: Main: 148484096 08:02:39 INFO - PROCESS | 7595 | 08:02:42 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:32916/tests/kraken/driver.html?testName=audio-beat-detection) 08:02:42 INFO - PROCESS | 7595 | RSS: Main: 198127616 08:02:42 INFO - PROCESS | 7595 | 08:02:45 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:32916/tests/kraken/driver.html?testName=audio-dft) 08:02:46 INFO - PROCESS | 7595 | RSS: Main: 192557056 08:02:46 INFO - PROCESS | 7595 | 08:02:48 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:32916/tests/kraken/driver.html?testName=audio-fft) 08:02:49 INFO - PROCESS | 7595 | RSS: Main: 282906624 08:02:49 INFO - PROCESS | 7595 | 08:02:51 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:32916/tests/kraken/driver.html?testName=audio-oscillator) 08:02:52 INFO - PROCESS | 7595 | RSS: Main: 191705088 08:02:52 INFO - PROCESS | 7595 | 08:02:54 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:32916/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:02:54 INFO - PROCESS | 7595 | RSS: Main: 192188416 08:02:54 INFO - PROCESS | 7595 | 08:02:57 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:32916/tests/kraken/driver.html?testName=imaging-darkroom) 08:02:58 INFO - PROCESS | 7595 | RSS: Main: 226578432 08:02:58 INFO - PROCESS | 7595 | 08:03:01 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:32916/tests/kraken/driver.html?testName=imaging-desaturate) 08:03:02 INFO - PROCESS | 7595 | RSS: Main: 225210368 08:03:02 INFO - PROCESS | 7595 | 08:03:05 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:32916/tests/kraken/driver.html?testName=json-parse-financial) 08:03:05 INFO - PROCESS | 7595 | RSS: Main: 224436224 08:03:05 INFO - PROCESS | 7595 | 08:03:07 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:32916/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:03:07 INFO - PROCESS | 7595 | RSS: Main: 203714560 08:03:07 INFO - PROCESS | 7595 | 08:03:09 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:03:10 INFO - PROCESS | 7595 | RSS: Main: 204513280 08:03:10 INFO - PROCESS | 7595 | 08:03:12 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:03:12 INFO - PROCESS | 7595 | RSS: Main: 210034688 08:03:12 INFO - PROCESS | 7595 | 08:03:15 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:03:15 INFO - PROCESS | 7595 | RSS: Main: 216834048 08:03:15 INFO - PROCESS | 7595 | 08:03:18 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:03:19 INFO - PROCESS | 7595 | RSS: Main: 216354816 08:03:19 INFO - PROCESS | 7595 | 08:03:20 INFO - PROCESS | 7595 | Cycle 1(1): loaded http://localhost:32916/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:32916/tests/kraken/driver.html?testName=ai-astar) 08:03:21 INFO - PROCESS | 7595 | RSS: Main: 258023424 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | __start_tp_report 08:03:21 INFO - PROCESS | 7595 | _x_x_mozilla_page_load 08:03:21 INFO - PROCESS | 7595 | _x_x_mozilla_page_load_details 08:03:21 INFO - PROCESS | 7595 | |i|pagename|runs| 08:03:21 INFO - PROCESS | 7595 | |0;ai-astar;109;105;109;109;110;108;108;111;108;109 08:03:21 INFO - PROCESS | 7595 | |1;audio-beat-detection;150;124;129;175;133;126;153;125;156;123 08:03:21 INFO - PROCESS | 7595 | |2;audio-dft;160;160;162;159;160;160;159;159;160;161 08:03:21 INFO - PROCESS | 7595 | |3;audio-fft;75;76;75;124;76;77;75;75;104;75 08:03:21 INFO - PROCESS | 7595 | |4;audio-oscillator;84;84;110;84;84;86;123;84;85;128 08:03:21 INFO - PROCESS | 7595 | |5;imaging-gaussian-blur;118;118;116;117;117;117;116;117;117;117 08:03:21 INFO - PROCESS | 7595 | |6;imaging-darkroom;147;145;145;147;146;146;145;146;145;145 08:03:21 INFO - PROCESS | 7595 | |7;imaging-desaturate;95;95;95;96;95;95;96;95;95;94 08:03:21 INFO - PROCESS | 7595 | |8;json-parse-financial;49;48;50;50;50;50;51;50;52;49 08:03:21 INFO - PROCESS | 7595 | |9;json-stringify-tinderbox;60;60;59;60;60;59;59;59;60;59 08:03:21 INFO - PROCESS | 7595 | |10;stanford-crypto-aes;81;81;76;75;77;76;77;82;111;76 08:03:21 INFO - PROCESS | 7595 | |11;stanford-crypto-ccm;127;120;124;115;117;116;124;117;130;120 08:03:21 INFO - PROCESS | 7595 | |12;stanford-crypto-pbkdf2;153;153;151;157;151;155;152;153;150;153 08:03:21 INFO - PROCESS | 7595 | |13;stanford-crypto-sha256-iterative;61;60;62;62;62;61;60;59;60;60 08:03:21 INFO - PROCESS | 7595 | __end_tp_report 08:03:21 INFO - PROCESS | 7595 | __start_cc_report 08:03:21 INFO - PROCESS | 7595 | _x_x_mozilla_cycle_collect,1559 08:03:21 INFO - PROCESS | 7595 | __end_cc_report 08:03:21 INFO - PROCESS | 7595 | __startTimestamp1469113401003__endTimestamp 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | ------- Summary: start ------- 08:03:21 INFO - PROCESS | 7595 | Number of tests: 14 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#0] ai-astar Cycles:10 Average:108.60 Median:109.00 stddev:1.58 (1.4%) stddev-sans-first:1.67 08:03:21 INFO - PROCESS | 7595 | Values: 109.0 105.0 109.0 109.0 110.0 108.0 108.0 111.0 108.0 109.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#1] audio-beat-detection Cycles:10 Average:139.40 Median:141.50 stddev:17.90 (12.6%) stddev-sans-first:18.57 08:03:21 INFO - PROCESS | 7595 | Values: 150.0 124.0 129.0 175.0 133.0 126.0 153.0 125.0 156.0 123.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:0.94 (0.6%) stddev-sans-first:1.00 08:03:21 INFO - PROCESS | 7595 | Values: 160.0 160.0 162.0 159.0 160.0 160.0 159.0 159.0 160.0 161.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#3] audio-fft Cycles:10 Average:83.20 Median:75.00 stddev:16.92 (22.6%) stddev-sans-first:17.68 08:03:21 INFO - PROCESS | 7595 | Values: 75.0 76.0 75.0 124.0 76.0 77.0 75.0 75.0 104.0 75.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#4] audio-oscillator Cycles:10 Average:95.20 Median:84.00 stddev:17.90 (21.3%) stddev-sans-first:18.52 08:03:21 INFO - PROCESS | 7595 | Values: 84.0 84.0 110.0 84.0 84.0 86.0 123.0 84.0 85.0 128.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 08:03:21 INFO - PROCESS | 7595 | Values: 118.0 118.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.73 08:03:21 INFO - PROCESS | 7595 | Values: 147.0 145.0 145.0 147.0 146.0 146.0 145.0 146.0 145.0 145.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 08:03:21 INFO - PROCESS | 7595 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 95.0 95.0 94.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:1.10 (2.2%) stddev-sans-first:1.12 08:03:21 INFO - PROCESS | 7595 | Values: 49.0 48.0 50.0 50.0 50.0 50.0 51.0 50.0 52.0 49.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:60.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 08:03:21 INFO - PROCESS | 7595 | Values: 60.0 60.0 59.0 60.0 60.0 59.0 59.0 59.0 60.0 59.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:77.00 stddev:10.77 (14.0%) stddev-sans-first:11.42 08:03:21 INFO - PROCESS | 7595 | Values: 81.0 81.0 76.0 75.0 77.0 76.0 77.0 82.0 111.0 76.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#11] stanford-crypto-ccm Cycles:10 Average:121.00 Median:122.00 stddev:5.06 (4.1%) stddev-sans-first:4.87 08:03:21 INFO - PROCESS | 7595 | Values: 127.0 120.0 124.0 115.0 117.0 116.0 124.0 117.0 130.0 120.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.80 Median:153.00 stddev:2.04 (1.3%) stddev-sans-first:2.17 08:03:21 INFO - PROCESS | 7595 | Values: 153.0 153.0 151.0 157.0 151.0 155.0 152.0 153.0 150.0 153.0 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - PROCESS | 7595 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:60.70 Median:61.00 stddev:1.06 (1.7%) stddev-sans-first:1.12 08:03:21 INFO - PROCESS | 7595 | Values: 61.0 60.0 62.0 62.0 62.0 61.0 60.0 59.0 60.0 60.0 08:03:21 INFO - PROCESS | 7595 | -------- Summary: end -------- 08:03:21 INFO - PROCESS | 7595 | 08:03:21 INFO - TEST-INFO | 7595: exit 0 08:03:21 INFO - TEST-OK | kraken | took 60311ms 08:03:21 INFO - SUITE-END | took 1173s 08:03:22 INFO - Completed test suite (00:19:33) 08:03:22 INFO - javascript benchmark 08:03:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3271.7219036381903, 3303.702666759992, 3328.467445208767, 3343.0973637117977, 3358.110100823281, 5420.051490989076, 5500.917486237709, 5511.338639363282, 5514.5864060195545, 5534.806281780138, 5453.64551304166, 5495.0, 5511.0, 5513.338399392069, 5546.168074788784, 4848.151573474628, 4966.776495057722, 4989.251612258154, 5022.899542009158, 5055.064813009605, 4802.183628783097, 4926.113299606088, 4950.232713929328, 4957.330760347361, 4957.727324997142, 4925.75371231437, 4984.800607975677, 5017.67385119966, 5033.094043072246, 5046.21783623539, 5102.158143906251, 5103.209002604603, 5103.2855400244, 5116.411612664554, 5145.202493613478, 4859.0767754126955, 4940.925886111711, 4986.127427700174, 5021.096202683552, 5022.196448568212, 5290.841274761725, 5307.867303317448, 5318.7074710891275, 5348.786048558053, 5350.571954243651, 5822.184894114827, 5854.443827836349, 5896.587238893279, 5898.7640494380175, 5913.113033045035, 5785.190073389729, 5833.445822646841, 5851.093080572518, 5855.297364316267, 5921.822345329647, 5485.75314110862, 5518.751656175482, 5529.419410961871, 5531.25328080708, 5536.972315138479, 5775.451332123442, 5984.2818861736, 5998.3701711320555, 5999.520038396918, 6007.15899774032, 5618.91571626426, 5662.462066103713, 5687.03320435527, 5746.511546518593, 5754.05058165398, 5843.328017277982, 5847.298324201123, 5851.561132915049, 5860.033094539353, 5867.530597552271, 5629.8592535186945, 5709.0865461525955, 5790.88418231633, 5803.071508558611, 5804.680742559115, 5724.914126288109, 5823.272090988627, 5825.679587622637, 5828.009238429478, 5868.941310586925, 4490.550944905549, 4501.977490112528, 4504.48198457175, 4504.527024662429, 4527.0, 3987.4018897165656, 3989.162275922031, 4033.0, 4044.5753195914594, 4080.7959602019782, 5535.529479994165, 5551.111822108443, 5556.888862222814, 5560.054790685593, 5562.749676264578, 5849.181114643953, 5853.9170253503235, 5857.7656893724625, 5859.824205273849, 5889.234399528034, 5401.783928642811, 5463.890722185534, 5506.146547285097, 5525.502704756591, 5579.414161512983, 4924.3105965164905, 4956.083124621884, 4960.801567937242, 4966.453690094087, 4974.17926042199, 6052.54605904559, 6112.633242005494, 6114.2662880453745, 6116.694165291718, 6123.38766123393, 5706.201131841629, 5763.5965482417505, 5818.563607729522, 5824.534037276923, 5862.501687356623, 6119.663418511846, 6120.846978825387, 6127.632342059491, 6137.294211165595, 6141.600795948428, 5598.804041858515, 5763.6541807491685, 5766.135079738073, 5772.826815195551, 5777.768889244383, 5769.59612827098, 5778.653280802997, 5833.854153646193, 5865.706714664335, 5874.500667443315, 5670.432956704297, 5701.486866182064, 5704.172894930295, 5706.372299047019, 5720.628159169807, 5425.620206585501, 5625.353084395347, 5700.515456186271, 5717.056685646821, 5719.8856022880145, 5561.221429000024, 5655.066913959151, 5657.15142728594, 5661.433856614306, 5691.402402747817, 5773.364929857797, 5800.216970709069, 5819.301683797971, 5822.417758224144, 5826.883462330815, 5503.477169668875, 5570.275864137799, 5593.916091258553, 5627.184058311604, 5670.943290567042], "value": 5395.669677652667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.7092595554134, 646.5700309294319, 650.1775254303307, 662.2417332154681, 664.4352300544534, 33120.5031924521, 36930.44604330934, 46850.82872928174, 47739.0, 47774.0, 49538.0, 49893.0, 49933.25100123501, 50057.74971125148, 50113.0, 8082.43422960393, 9171.174594286513, 9318.394304370195, 9319.580618872167, 10942.726431839228, 10647.254692171551, 11059.668209953714, 11062.502187401546, 11063.446827658625, 11064.944675276653, 11153.776924461506, 11183.44082795861, 11205.495752691148, 11207.831882521768, 11215.38315392653, 8285.917140828606, 8419.494830310201, 8449.619767110495, 11060.557577696882, 11071.16966227528, 10689.572417103307, 10775.299605525615, 11124.555017799279, 11139.387333696643, 11144.665660030212, 46084.53915460836, 46584.76707616474, 46715.29927051097, 46742.065158696976, 46757.53242467566, 3586.1572530455323, 3705.962940370616, 3712.276106159273, 3720.5163328767358, 3851.1912498375373, 35046.47430288548, 35263.82368088169, 35730.46404303938, 35946.460803087975, 35974.0, 4694.305293356913, 4802.30366596845, 4867.172580661296, 4883.536064073907, 4963.007398520311, 388603.0, 389739.0, 391685.0, 392193.0, 393122.99999999715, 39224.60775392267, 70247.6487617569, 70782.64608676924, 70822.2917770826, 70824.0, 39646.603533964866, 42037.78981105136, 42052.57947420548, 42066.0, 42069.36895946563, 17757.200925958103, 18039.819601804076, 18051.0, 18057.819421805612, 18059.819401806075, 12344.3827808611, 14238.216898070705, 14520.927395363167, 14640.341184646504, 15060.849391505944, 9398.812023759487, 9540.570674319672, 9543.856842147374, 9552.808943821085, 9616.95191524038, 3637.836297366625, 3662.853485860589, 3679.0, 3681.7606855554304, 3694.833732482045, 1056.0, 1086.8913108689226, 1104.4643347976225, 1201.0751721174647, 1233.1368042370198, 34281.82859085689, 34691.30617387638, 34694.0, 35008.31581102845, 35074.0, 1147.9081673466103, 1211.5214490276292, 1214.8724383939737, 1257.9874201258053, 1290.8192853000587, 18057.548561286072, 18202.544936376697, 18311.63376732458, 18356.816431835778, 18392.356267530573, 609359.0, 614093.0, 614788.0, 617049.0, 619694.0, 43874.561254387045, 44248.55751442444, 44514.0, 44537.55462445334, 44658.77670611626, 1357.429879450633, 1373.0800363756307, 1421.736978658972, 1472.0063956829097, 1477.416420513891, 31116.510679146068, 35033.29933401369, 35036.35913925303, 35076.82461587676, 35722.0, 56973.43026569847, 57332.1400179006, 57573.4242657568, 57622.42377576171, 57698.134527982955, 410268.0, 411026.0, 411806.0, 412443.0, 412894.0, 31044.844775775975, 31601.683983160794, 40483.0, 47074.0, 48186.27720584124, 52629.7368513155, 52807.0, 53195.73402132965, 53256.73371633117, 53575.464245358606, 43707.12585748204, 44224.0, 44420.33369499525, 44916.77541612271, 45127.09745805, 43246.56753432551, 43836.780816095714, 43893.561064390226, 44325.55674443214, 44692.32961505645, 44302.556974431136, 44323.33514997213, 44388.556114439736, 45138.322925156805, 46101.0, 37489.812550938164, 40124.1975160501, 40511.18977620372, 40846.79576602098, 41193.794031029654, 30016.549751754177, 30188.6980581793, 30193.849030754707, 30694.539581906734, 30708.618112185453], "value": 20808.210495851115, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.69179960929114, 419.2704693832732, 421.4016097142058, 422.2041451863238, 429.5811583705887, 19117.330893418715, 19970.753083154064, 20469.0, 20625.896870515626, 20805.16779328827, 19491.902540487314, 19793.307234246804, 20047.59904801903, 20631.1747530099, 20708.585828283463, 5668.744906479218, 5846.532277417818, 5896.705164741746, 5901.793437229698, 5903.203067585864, 4117.300058989979, 4253.8723838284905, 5800.332961709394, 5850.268716410449, 6476.643784591869, 6537.967310163443, 6549.083128362033, 6556.114924485181, 6570.605763654172, 6573.046908198308, 6391.904121438136, 6461.095446637474, 6541.607503549779, 6553.574017688835, 6561.409473147416, 3903.394973779069, 5461.317335333083, 5797.652140871562, 5804.535637149018, 5810.622309549865, 23861.642075368884, 24150.758492415025, 24171.758282417126, 24175.0, 24369.756302436926, 2294.300238427279, 2436.1838784007377, 2566.897324107052, 2689.38144226827, 3047.040182342555, 10355.5340009699, 10718.892811071868, 10991.670249892515, 12744.6813829655, 14698.926505367512, 1883.52911772057, 1890.1683259365973, 2011.6077364913847, 2071.8238949689294, 2138.9786102138937, 81617.0, 84123.57938210331, 84354.99999999939, 84960.57519712424, 85759.0, 11459.656210313704, 11509.25189862673, 11527.193096483334, 11586.88413115858, 11816.88183118175, 16230.675386492205, 16283.83716162847, 16344.283850195558, 16349.346026159059, 16378.0, 8263.132371101068, 8430.199131082538, 8529.82940341202, 8597.52713600758, 9186.86219706705, 8785.0, 8878.644854205888, 8879.33404994628, 8940.418872773249, 8974.28205743539, 4937.08663897173, 4969.229769385751, 4989.201727723546, 5003.54968052877, 5035.7985680572365, 1984.1666500069716, 2000.5598768270956, 2023.6357455657828, 2080.157536197842, 2125.968110478344, 775.5463054113407, 885.8051228729669, 919.3564504846634, 1019.2327829941744, 1076.4025965589017, 19649.803501964798, 19702.40892773219, 19917.900410498143, 19938.80875749975, 20017.399478015683, 1068.8824229334766, 1081.5403453532217, 1088.7768007558432, 1096.4353358020626, 1147.9540818367173, 10004.49977501122, 11106.16703747206, 11121.777564448666, 11250.774984500264, 11301.886981130083, 454886.0, 456844.0, 466202.0, 499918.0, 517791.0, 97845.0, 98225.50887245518, 99710.50144749375, 100393.49803251083, 101230.49384753029, 1067.1836045425257, 1110.5502271580024, 1126.5437497813398, 1130.711668524521, 1131.5586921100942, 18010.189541470405, 18554.907225464325, 18630.627387451903, 18684.0, 18953.620927581094, 42409.151816964106, 42583.787081064394, 42962.78518607387, 43639.732618722766, 44298.335524966504, 58719.4128058714, 60651.393486064575, 61912.69043654753, 62065.06902396378, 62434.68782656058, 8842.336824738042, 8896.466212027299, 8928.0, 8930.508822014592, 9110.453372797654, 9856.605735770489, 11251.831222531504, 13270.1374410663, 13488.460461581426, 14245.358958847084, 11646.88353116458, 11840.940795295968, 12135.817962730389, 12431.191972521758, 12638.494460221857, 11113.277636953571, 11573.13201509907, 11637.941810290895, 11714.062874969812, 11827.704307392383, 11928.659975206956, 11978.880211197777, 12099.213551119148, 12218.75562488763, 12220.205686630341, 8609.311255099452, 8807.383483156118, 8876.556172191236, 9037.141471560191, 9041.59312830911, 10175.389476631426, 12503.187292825936, 12518.499260029497, 12553.49786008586, 12642.98856091492], "value": 9638.03066036701, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1202.488942199566, 1524.1160127126263, 1570.0108931373236, 1751.2994802079168, 1862.0317434933843, 8010.319122874555, 9177.08229177082, 9511.857322140173, 9514.191293740023, 9749.0, 9452.432854028762, 9602.423854568731, 9620.518974051305, 9628.13346798788, 9632.0, 2156.1914282144194, 2214.7563767985507, 2400.2079313826393, 2460.680111585496, 2498.1756020513276, 2507.749225077496, 2545.9745402546014, 2550.2731721459386, 2550.5409026375246, 2552.8213025088253, 2421.225207933462, 2471.270975062361, 2476.2571228631336, 2477.6531285620026, 2494.3514686181543, 2010.6079314533672, 2311.15642790382, 2379.95240095198, 2398.4243781492314, 2411.8191135664806, 2448.4123810285582, 2455.6439316299216, 2473.356927198923, 2475.789557887582, 2480.541099896525, 9573.138417542385, 9652.613895444174, 9655.372400793925, 9677.177439917616, 9679.467629280387, 5526.50261476469, 5679.318481782171, 5689.9146512802345, 5699.088145896637, 5701.971490142564, 3594.388953877847, 3633.3096711624707, 3685.594584595692, 3707.3141468828085, 3710.517632707758, 1224.5591587028662, 1234.9567765128268, 1313.6256166992457, 1333.4999375234288, 1370.0, 13315.40080696371, 13415.86584134156, 13425.731485370336, 13446.93276533611, 13477.595672129852, 4601.8849528761375, 4692.41344831896, 4734.4081989751285, 4746.121967436035, 4752.120857641347, 10462.947685261677, 10484.423356715453, 10512.842307365396, 10516.737081572868, 10517.0, 5508.586855985737, 5509.834704958857, 5515.062439385314, 5516.641418307797, 5519.39286678465, 4521.570450807168, 5049.419316778544, 5178.844634660966, 5188.195829646411, 5216.634835561475, 3984.143409167043, 3995.900102497461, 4081.959180408158, 4111.6299533042165, 4121.670266378683, 1163.9979235714557, 1164.9242799218023, 1172.8358029875824, 1173.2256710570982, 1176.2707121584672, 951.6003278622995, 953.1469334945185, 1038.7610849504656, 1039.589362201926, 1040.3029969920171, 6417.711202995783, 6451.741930322828, 6556.344365563501, 6595.538312318187, 6599.538032337787, 459.54275495881143, 460.62689221730506, 466.55210997442157, 1101.146611376174, 1131.083822104082, 4279.700420970502, 4479.10417916418, 4512.34570987205, 4527.864164075083, 4558.635309175258, 11702.531898724126, 12049.216800908089, 12058.457369418396, 12063.155579109378, 12079.3356365399, 11194.160437967184, 11670.358130302915, 11691.064714822794, 11974.700632484084, 11986.700332491582, 8788.472691638393, 8982.955085224532, 8999.77500562478, 9001.819963600692, 9003.684871029482, 6410.391012853772, 6664.866702666017, 6762.560433571802, 6834.214065382345, 6851.794446166623, 10663.200259980378, 10768.138548915911, 11001.559937602406, 11015.83476247841, 11045.0, 10906.781864362827, 11065.557377704801, 11081.058110060736, 11172.88827111751, 11413.60052398162, 7291.4166866649475, 8455.450395724258, 8588.570571471277, 8673.91326086731, 9024.142706442877, 9063.274938004812, 9357.157855793012, 9372.718818435458, 9391.107844754737, 9437.858432123387, 8234.752957411287, 8343.374246931626, 8399.748007559783, 8414.158584141536, 8543.10297418787, 8159.877601836096, 8193.877091843746, 8285.917140828515, 8402.663893444194, 8407.70573029941, 7185.53294035886, 7266.96366518164, 7307.378872795659, 7322.194558598443, 7353.889691654523, 5284.841454756363, 5390.892182156414, 5420.891582168256, 5443.292371991616, 5581.5813813963305, 5624.240727501735, 5631.211630371669, 5736.942630573641, 5758.971205143947, 5777.133429985536], "value": 4900.410556207059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.82756380464065, 342.4469481786914, 355.236242079529, 355.53069947669053, 355.7740834570048, 5286.31277933868, 5413.648112872661, 5496.697681627509, 5528.364238112618, 5547.972260138698, 5294.311739473863, 5382.273393091931, 5387.568994480442, 5407.270018547495, 5407.5133238008575, 4297.441332626754, 4300.311950087978, 4303.0, 4311.417958575599, 4315.115401342718, 4070.3690927906223, 4181.289180839264, 4294.098239369736, 4303.956960430387, 4314.956850431503, 4267.871963841089, 4308.913821723564, 4326.675499337546, 4337.611964171467, 4345.739255644656, 4263.552327005682, 4287.856071964018, 4296.785160741966, 4307.310830267143, 4311.61195492404, 3722.758020728644, 3842.634949679776, 3864.3237433449317, 3865.357223180137, 3878.7090968177217, 5157.71632560209, 5214.661047031968, 5219.0, 5233.293505376763, 5254.00173966945, 4866.489018653061, 4876.195807551228, 4878.341423907798, 4885.633577481668, 4889.8777530561665, 5346.973265133688, 5660.0, 5668.348139963916, 5709.943660422794, 5735.0214176109175, 4196.769177695225, 4222.831086756526, 4242.91514169718, 4244.790234783072, 4252.978735106305, 5892.322382925976, 5939.9109013364405, 5954.4938680212235, 5967.0, 5977.622643206574, 4205.306124489486, 4218.620324170839, 4231.640310573574, 4286.442762440863, 4309.827606895752, 4246.893827654271, 4258.616724494748, 4262.211490874198, 4282.379055037002, 4297.720648157922, 4797.7601119943865, 4816.975915120402, 4841.540053694894, 4858.878528036827, 4885.340479035356, 5563.972180139073, 5674.659520428788, 5730.684812335278, 5735.483806457439, 5785.334686511021, 1406.0438901547045, 1477.6479521237948, 1527.3279756906925, 1530.8239552451387, 1537.269796846489, 971.9757006074905, 989.2234595842373, 1001.0940099210227, 1001.1440218613127, 1018.4500369800373, 4926.531979461945, 4937.530934561211, 4972.65191436596, 4991.3511243538105, 5061.342025536656, 5167.382832017603, 5175.327207463006, 5215.217717342429, 5222.224443689164, 5264.894702105937, 4570.0611956691955, 4922.704637721748, 4925.876853078702, 4944.579710724557, 4980.77586508608, 2764.847933363684, 2765.1151631477733, 2783.900359358083, 2786.247713117447, 2794.636697229347, 5318.521333080042, 5357.35711714589, 5375.5162035416215, 5421.2681288025615, 5423.647462914898, 5298.443663415286, 5333.493318134771, 5618.747156377891, 6047.606905551125, 6061.939380606225, 6139.969300153471, 6185.165002724664, 6195.721192546346, 6216.347283535164, 6236.18929539157, 5634.464490978994, 5790.033826828636, 5914.467697907209, 5929.347771745191, 5938.524918006463, 5814.5639077068545, 5825.679362763353, 5826.854328641818, 5851.707414629166, 5889.970550147222, 6168.845778855564, 6169.629822210682, 6175.84560385994, 6184.288806787277, 6229.750809967551, 4111.9177616448105, 4201.768902710258, 4211.599898009684, 4223.796218077833, 4241.724287921275, 5707.915496055736, 5822.330432000204, 5897.9115313271195, 5921.407859214217, 5960.168919763251, 5628.9305032043785, 5692.003899317686, 5710.600257981901, 5724.597473618824, 5763.279590051244, 5619.831405057855, 5732.914006289992, 5767.134929760569, 5772.095208581242, 5788.989725342883, 5456.863578410572, 5663.292088488939, 5669.291338582677, 5670.036093863971, 5674.347450043298, 5704.80033198836, 5739.971300143473, 5766.308043034863, 5773.3072031356505, 5790.1893734877995, 5466.972665136808, 5652.63257888236, 5658.151277308437, 5701.942980570141, 5726.3944905067265], "value": 4316.903982650969, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [780.6643143448315, 793.7102957420543, 820.9548474833882, 860.8536548786706, 884.5886662701845, 15762.76355854663, 16343.673126537462, 16574.0, 16606.0, 16617.16914154292, 15418.460353887589, 15661.216939153053, 15687.60780980476, 15721.606959825982, 15737.291821868015, 873.3755364914085, 882.4440602420466, 887.4320434921656, 889.1819526036062, 891.754767438953, 884.1335491218578, 884.995575022124, 885.6545947080643, 886.2998231397188, 886.4459712679575, 932.9626814927428, 933.6872147830485, 936.5317341329335, 937.6296362936636, 937.6718148647986, 927.8051609162084, 927.9814403711922, 928.7120992492349, 929.330881765128, 929.9628014879397, 682.747383468115, 683.8803209438378, 685.2120061928772, 685.3044160177424, 685.5372623479132, 14340.139591624431, 14402.135871847722, 14455.20496372699, 14456.349464274133, 14470.49353272641, 12995.480180792756, 13796.862031379758, 14142.434302627882, 14471.42114315426, 14547.199904005276, 11909.166358354918, 11929.821052684216, 12199.0, 12387.132900696955, 12623.368831558477, 4005.959940400588, 4048.655864251513, 4090.672746180328, 4096.09885825121, 4183.184279065584, 77082.0, 77157.61421192858, 77656.22343776604, 77934.61032694801, 78625.0, 11740.119491038036, 11887.881121188677, 11988.940055299841, 12146.878531214576, 12210.816837747441, 20019.29932452357, 20049.799502005084, 20064.799352006292, 20105.29631462921, 20108.798912010694, 8858.069902660256, 8930.419522731001, 8943.418677785923, 9042.728718138467, 9157.0, 4926.162552366106, 4999.800007999639, 5017.498250174954, 5078.568321692697, 5153.484651534892, 3380.695737383647, 3453.4819777033144, 3465.8440370183403, 3465.878694245689, 3469.7918124912585, 651.7979426377838, 659.3768888400416, 663.5056882622478, 664.1299897134769, 671.0370618162951, 655.9344065593498, 700.3066963705894, 754.1930134755868, 764.3503022430889, 783.5847001089431, 10576.629817956335, 10742.94628526868, 10766.56933722658, 10810.621628242974, 10826.18803589734, 715.8174665460274, 746.7199800074972, 750.3846845586676, 759.4721668440492, 767.8310771630232, 4862.197737373364, 4890.437599676012, 4953.578945789648, 4967.726775027409, 4987.0524600325825, 116759.0, 116899.41550292364, 117627.0, 120063.0, 123728.0, 78896.0, 98711.0, 101314.0, 102775.48612256891, 102833.0, 83648.58175709083, 85783.57108214544, 89876.5506172465, 90253.09746902446, 92189.0, 10523.263371563917, 10675.410621509307, 10881.292715973437, 11028.338299702044, 11191.384473854016, 50864.23703644522, 52283.0, 52552.0, 52783.47216527785, 54322.18516722332, 76691.61654191693, 79815.60092199502, 80635.0, 81207.59396202982, 81922.59038705006, 12714.61856144317, 13299.401526931504, 13409.32953352348, 13578.852574021763, 13706.588802335946, 15248.847511524742, 15322.693546129238, 15497.07017578949, 15512.146831924352, 15592.844071559139, 14800.7779883304, 14864.182469964257, 14984.325705343068, 15295.158766267961, 15500.0, 13767.793483097561, 14700.926495367456, 14812.259387030821, 14889.404423823358, 14994.100353978796, 14548.345324460637, 14629.561113166623, 14636.341364638403, 14804.0, 15245.237738112828, 12032.518699251932, 12059.57791477294, 12181.512739490321, 12381.876181238433, 12514.374281286082, 12230.893903503304, 12379.69050773738, 12464.586424010093, 12465.0, 12537.686557835763], "value": 7957.054802179226, "unit": "score"}], "name": "dromaeo_css", "value": 7529.132733442027, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 105.0, 109.0, 109.0, 110.0, 108.0, 108.0, 111.0, 108.0, 109.0], "value": 108.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 124.0, 129.0, 175.0, 133.0, 126.0, 153.0, 125.0, 156.0, 123.0], "value": 139.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 162.0, 159.0, 160.0, 160.0, 159.0, 159.0, 160.0, 161.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 124.0, 76.0, 77.0, 75.0, 75.0, 104.0, 75.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 110.0, 84.0, 84.0, 86.0, 123.0, 84.0, 85.0, 128.0], "value": 95.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 118.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 147.0, 146.0, 146.0, 145.0, 146.0, 145.0, 145.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 48.0, 50.0, 50.0, 50.0, 50.0, 51.0, 50.0, 52.0, 49.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 59.0, 59.0, 59.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 76.0, 75.0, 77.0, 76.0, 77.0, 82.0, 111.0, 76.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 120.0, 124.0, 115.0, 117.0, 116.0, 124.0, 117.0, 130.0, 120.0], "value": 121.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 153.0, 151.0, 157.0, 151.0, 155.0, 152.0, 153.0, 150.0, 153.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 62.0, 62.0, 62.0, 61.0, 60.0, 59.0, 60.0, 60.0], "value": 60.7, "unit": "score"}], "name": "kraken", "value": 1469.3, "alertThreshold": 2.0}]} 08:03:22 INFO - Return code: 0 08:03:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:03:22 ERROR - # TBPL SUCCESS # 08:03:22 INFO - Running post-action listener: _resource_record_post_action 08:03:22 INFO - ##### 08:03:22 INFO - ##### Finished run-tests step (success) 08:03:22 INFO - ##### 08:03:22 INFO - Running post-run listener: _resource_record_post_run 08:03:22 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 712704; Write bytes: 511156224; Read time: 92; Write time: 431892 08:03:22 INFO - TinderboxPrint: CPU usage
13.1% 08:03:22 INFO - TinderboxPrint: I/O read bytes / time
712,704 / 92 08:03:22 INFO - TinderboxPrint: I/O write bytes / time
511,156,224 / 431,892 08:03:22 INFO - TinderboxPrint: CPU idle
8,253.2 (86.9%) 08:03:22 INFO - TinderboxPrint: CPU user
1,198.9 (12.6%) 08:03:22 INFO - TinderboxPrint: Swap in / out
0 / 0 08:03:22 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 258420736; Read time: 0; Write time: 265768 08:03:22 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 688128; Write bytes: 247566336; Read time: 80; Write time: 165596 08:03:22 INFO - Running post-run listener: _upload_blobber_files 08:03:22 INFO - Blob upload gear active. 08:03:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:03:22 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 08:03:22 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'] 08:03:22 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 08:03:23 INFO - (blobuploader) - INFO - Open directory for files ... 08:03:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:03:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:03:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:24 INFO - (blobuploader) - INFO - Done attempting. 08:03:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:03:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:03:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:47 INFO - (blobuploader) - INFO - Done attempting. 08:03:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:03:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:03:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:03:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:03:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:03:48 INFO - (blobuploader) - INFO - Done attempting. 08:03:48 INFO - (blobuploader) - INFO - Iteration through files over. 08:03:48 INFO - Return code: 0 08:03:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:03:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:03:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e43987ae3994350b2eda9f264d2a9e18eda14cea2afe1c8764e37b6350c48d0a9edcf215ff522a8b81fd94d74f8fe63700187cdf5755ebdc0550d12bee9eed0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0c9979ba9983db3f72f63531f8be062ee9dff419c83de9f6229f2541b75cbc2f32793306e51d90fb39f3be9311ccb1d6641c157c0fac2212d657b10bdd933af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfb2147c860c053fa62b802fd1f39ef2a37d5b105f7bb7294ca73ef68495982a96cf60d844a20356e008cf37a1be54ca3439bafb0e6fe960236638831655a603"} 08:03:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:03:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:03:48 INFO - Contents: 08:03:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e43987ae3994350b2eda9f264d2a9e18eda14cea2afe1c8764e37b6350c48d0a9edcf215ff522a8b81fd94d74f8fe63700187cdf5755ebdc0550d12bee9eed0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0c9979ba9983db3f72f63531f8be062ee9dff419c83de9f6229f2541b75cbc2f32793306e51d90fb39f3be9311ccb1d6641c157c0fac2212d657b10bdd933af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfb2147c860c053fa62b802fd1f39ef2a37d5b105f7bb7294ca73ef68495982a96cf60d844a20356e008cf37a1be54ca3439bafb0e6fe960236638831655a603"} 08:03:48 INFO - Running post-run listener: copy_logs_to_upload_dir 08:03:48 INFO - Copying logs to upload dir... 08:03:48 INFO - mkdir: /builds/slave/test/build/upload/logs 08:03:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.566226 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-07-21 08:03:48.563605) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 08:03:48.567044) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437 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/e43987ae3994350b2eda9f264d2a9e18eda14cea2afe1c8764e37b6350c48d0a9edcf215ff522a8b81fd94d74f8fe63700187cdf5755ebdc0550d12bee9eed0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0c9979ba9983db3f72f63531f8be062ee9dff419c83de9f6229f2541b75cbc2f32793306e51d90fb39f3be9311ccb1d6641c157c0fac2212d657b10bdd933af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfb2147c860c053fa62b802fd1f39ef2a37d5b105f7bb7294ca73ef68495982a96cf60d844a20356e008cf37a1be54ca3439bafb0e6fe960236638831655a603"} build_url:https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011175 build_url: 'https://queue.taskcluster.net/v1/task/c6GUJaxcT-yeZjLy72Bbzw/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/e43987ae3994350b2eda9f264d2a9e18eda14cea2afe1c8764e37b6350c48d0a9edcf215ff522a8b81fd94d74f8fe63700187cdf5755ebdc0550d12bee9eed0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b0c9979ba9983db3f72f63531f8be062ee9dff419c83de9f6229f2541b75cbc2f32793306e51d90fb39f3be9311ccb1d6641c157c0fac2212d657b10bdd933af", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/dfb2147c860c053fa62b802fd1f39ef2a37d5b105f7bb7294ca73ef68495982a96cf60d844a20356e008cf37a1be54ca3439bafb0e6fe960236638831655a603"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-21 08:03:48.592678) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:03:48.593097) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1469112176.93307-951874437 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004445 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-21 08:03:48.678243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 08:03:48.678571) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-21 08:03:48.678926) ========= ========= Total master_lag: 0.18 =========