builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0084 starttime: 1463004161.88 results: success (0) buildid: 20160511140805 builduid: 4c66d5d41d264cf28ca32a0d23a89fa7 revision: 6581bbe179b96e153cbc54335681cfc5c9195d62 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:41.884925) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:41.885408) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:41.885703) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:41.906150) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:41.906551) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jjCDMRm0KF/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-11 15:02:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.2M=0.001s 2016-05-11 15:02:42 (12.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130038 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:42.054165) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:42.054495) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jjCDMRm0KF/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.026225 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:42.103075) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 15:02:42.103508) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6581bbe179b96e153cbc54335681cfc5c9195d62 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 6581bbe179b96e153cbc54335681cfc5c9195d62 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jjCDMRm0KF/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-11 15:02:42,178 truncating revision to first 12 chars 2016-05-11 15:02:42,178 Setting DEBUG logging. 2016-05-11 15:02:42,178 attempt 1/10 2016-05-11 15:02:42,178 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6581bbe179b9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 15:02:43,245 unpacking tar archive at: fx-team-6581bbe179b9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.316523 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 15:02:43.437447) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:43.437782) ========= script_repo_revision: 6581bbe179b96e153cbc54335681cfc5c9195d62 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:43.438169) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:43.438428) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 15:02:43.452073) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-11 15:02:43.452332) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jjCDMRm0KF/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 15:02:43 INFO - MultiFileLogger online at 20160511 15:02:43 in /builds/slave/test 15:02:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:02:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:02:43 INFO - {'append_to_log': False, 15:02:43 INFO - 'base_work_dir': '/builds/slave/test', 15:02:43 INFO - 'blob_upload_branch': 'Fx-Team', 15:02:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:02:43 INFO - 'branch': 'Fx-Team', 15:02:43 INFO - 'buildbot_json_path': 'buildprops.json', 15:02:43 INFO - 'config_files': ('talos/mac_config.py',), 15:02:43 INFO - 'default_actions': ('clobber', 15:02:43 INFO - 'read-buildbot-config', 15:02:43 INFO - 'download-and-extract', 15:02:43 INFO - 'populate-webroot', 15:02:43 INFO - 'create-virtualenv', 15:02:43 INFO - 'install', 15:02:43 INFO - 'run-tests'), 15:02:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:02:43 INFO - 'download_minidump_stackwalk': True, 15:02:43 INFO - 'download_symbols': 'ondemand', 15:02:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:02:43 INFO - 'tooltool.py': '/tools/tooltool.py', 15:02:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:02:43 INFO - '/tools/misc-python/virtualenv.py')}, 15:02:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:02:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:02:43 INFO - 'installer_path': 'installer.exe', 15:02:43 INFO - 'log_level': 'info', 15:02:43 INFO - 'log_name': 'talos', 15:02:43 INFO - 'log_to_console': True, 15:02:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:02:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:02:43 INFO - 'opt_config_files': (), 15:02:43 INFO - 'pip_index': False, 15:02:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:02:43 INFO - 'cmd': ('bash', 15:02:43 INFO - '-c', 15:02:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:02:43 INFO - 'enabled': True, 15:02:43 INFO - 'halt_on_failure': False, 15:02:43 INFO - 'name': 'check_screen_resolution'},), 15:02:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:02:43 INFO - 'cmd': ('bash', 15:02:43 INFO - '-c', 15:02:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:02:43 INFO - 'enabled': True, 15:02:43 INFO - 'halt_on_failure': False, 15:02:43 INFO - 'name': 'check_screen_resolution'},), 15:02:43 INFO - 'run_cmd_checks_enabled': True, 15:02:43 INFO - 'sps_profile': False, 15:02:43 INFO - 'sps_profile_interval': 0, 15:02:43 INFO - 'suite': 'dromaeojs', 15:02:43 INFO - 'system_bits': '32', 15:02:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:02:43 INFO - 'title': 't-yosemite-r7-0084', 15:02:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:02:43 INFO - 'use_talos_json': True, 15:02:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:02:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:02:43 INFO - 'work_dir': 'build'} 15:02:43 INFO - ##### 15:02:43 INFO - ##### Running clobber step. 15:02:43 INFO - ##### 15:02:43 INFO - Running pre-action listener: _resource_record_pre_action 15:02:43 INFO - Running main action method: clobber 15:02:43 INFO - rmtree: /builds/slave/test/build 15:02:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:02:45 INFO - Running post-action listener: _resource_record_post_action 15:02:45 INFO - ##### 15:02:45 INFO - ##### Finished clobber step (success) 15:02:45 INFO - ##### 15:02:45 INFO - ##### 15:02:45 INFO - ##### Running read-buildbot-config step. 15:02:45 INFO - ##### 15:02:45 INFO - Running pre-action listener: _resource_record_pre_action 15:02:45 INFO - Running main action method: read_buildbot_config 15:02:45 INFO - Using buildbot properties: 15:02:45 INFO - { 15:02:45 INFO - "project": "", 15:02:45 INFO - "product": "firefox", 15:02:45 INFO - "script_repo_revision": "production", 15:02:45 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:02:45 INFO - "repository": "", 15:02:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:02:45 INFO - "buildid": "20160511140805", 15:02:45 INFO - "pgo_build": "False", 15:02:45 INFO - "basedir": "/builds/slave/test", 15:02:45 INFO - "buildnumber": 13, 15:02:45 INFO - "slavename": "t-yosemite-r7-0084", 15:02:45 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:02:45 INFO - "platform": "yosemite_r7", 15:02:45 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:02:45 INFO - "branch": "fx-team", 15:02:45 INFO - "repo_path": "integration/fx-team", 15:02:45 INFO - "revision": "6581bbe179b96e153cbc54335681cfc5c9195d62", 15:02:45 INFO - "stage_platform": "macosx64", 15:02:45 INFO - "builduid": "4c66d5d41d264cf28ca32a0d23a89fa7", 15:02:45 INFO - "slavebuilddir": "test" 15:02:45 INFO - } 15:02:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 15:02:45 INFO - Running post-action listener: _resource_record_post_action 15:02:45 INFO - ##### 15:02:45 INFO - ##### Finished read-buildbot-config step (success) 15:02:45 INFO - ##### 15:02:45 INFO - ##### 15:02:45 INFO - ##### Running download-and-extract step. 15:02:45 INFO - ##### 15:02:45 INFO - Running pre-action listener: _resource_record_pre_action 15:02:45 INFO - Running main action method: download_and_extract 15:02:45 INFO - mkdir: /builds/slave/test/build/tests 15:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:45 INFO - https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:02:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 15:02:46 INFO - Downloaded 1199 bytes. 15:02:46 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 15:02:46 INFO - Using the following test package requirements: 15:02:46 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:02:46 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 15:02:46 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 15:02:46 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'jsshell-mac.zip'], 15:02:46 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 15:02:46 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 15:02:46 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 15:02:46 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 15:02:46 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 15:02:46 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 15:02:46 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 15:02:46 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:02:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:02:46 INFO - https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:02:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:02:48 INFO - Downloaded 18150527 bytes. 15:02:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:02:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:02:51 INFO - Return code: 0 15:02:51 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:02:51 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')]} 15:02:51 INFO - https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:02:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 15:02:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:02:52 INFO - Downloaded 18150527 bytes. 15:02:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:02:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:02:56 INFO - Return code: 0 15:02:56 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')]} 15:02:56 INFO - https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:02:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:02:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:02:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 15:02:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:02:57 INFO - Downloaded 10840152 bytes. 15:02:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:02:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:02:58 INFO - Return code: 0 15:02: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')]} 15:02:58 INFO - https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:02:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:02:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:02:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 15:02:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 15:03:00 INFO - Downloaded 109798329 bytes. 15:03:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:03:00 INFO - mkdir: /builds/slave/test/properties 15:03:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:03:00 INFO - Writing to file /builds/slave/test/properties/build_url 15:03:00 INFO - Contents: 15:03:00 INFO - build_url:https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 15:03:02 INFO - Running post-action listener: _resource_record_post_action 15:03:02 INFO - Running post-action listener: set_extra_try_arguments 15:03:02 INFO - ##### 15:03:02 INFO - ##### Finished download-and-extract step (success) 15:03:02 INFO - ##### 15:03:02 INFO - ##### 15:03:02 INFO - ##### Running populate-webroot step. 15:03:02 INFO - ##### 15:03:02 INFO - Running pre-action listener: _resource_record_pre_action 15:03:02 INFO - Running main action method: populate_webroot 15:03:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:03:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:03:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:03:02 INFO - '--tppagecycles', 15:03:02 INFO - '7'], 15:03:02 INFO - 'tests': ['tsvgm']}}, 15:03:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:03:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:03:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:03:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:03:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:03:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:03:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['damp', 'tps']}, 15:03:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'tests': ['damp', 'tps']}, 15:03:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['dromaeo_dom']}, 15:03:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:03:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['a11yr', 15:03:02 INFO - 'ts_paint', 15:03:02 INFO - 'tpaint', 15:03:02 INFO - 'sessionrestore', 15:03:02 INFO - 'sessionrestore_no_auto_restore', 15:03:02 INFO - 'tabpaint']}, 15:03:02 INFO - 'other-e10s': {'tests': ['a11yr', 15:03:02 INFO - 'ts_paint', 15:03:02 INFO - 'tpaint', 15:03:02 INFO - 'sessionrestore', 15:03:02 INFO - 'sessionrestore_no_auto_restore', 15:03:02 INFO - 'tabpaint']}, 15:03:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:03:02 INFO - 'ts_paint', 15:03:02 INFO - 'tpaint', 15:03:02 INFO - 'sessionrestore', 15:03:02 INFO - 'sessionrestore_no_auto_restore', 15:03:02 INFO - 'tabpaint']}, 15:03:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:03:02 INFO - 'ts_paint', 15:03:02 INFO - 'tpaint', 15:03:02 INFO - 'sessionrestore', 15:03:02 INFO - 'sessionrestore_no_auto_restore', 15:03:02 INFO - 'tabpaint']}, 15:03:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['a11yr', 15:03:02 INFO - 'ts_paint', 15:03:02 INFO - 'tpaint', 15:03:02 INFO - 'sessionrestore', 15:03:02 INFO - 'sessionrestore_no_auto_restore', 15:03:02 INFO - 'tabpaint']}, 15:03:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['a11yr', 15:03:02 INFO - 'ts_paint', 15:03:02 INFO - 'tpaint', 15:03:02 INFO - 'sessionrestore', 15:03:02 INFO - 'sessionrestore_no_auto_restore', 15:03:02 INFO - 'tabpaint']}, 15:03:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['tsvgx', 15:03:02 INFO - 'tsvgr_opacity', 15:03:02 INFO - 'tart', 15:03:02 INFO - 'tscrollx', 15:03:02 INFO - 'cart']}, 15:03:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:03:02 INFO - 'tsvgr_opacity', 15:03:02 INFO - 'tart', 15:03:02 INFO - 'tscrollx', 15:03:02 INFO - 'cart']}, 15:03:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'talos_options': ['--disable-e10s'], 15:03:02 INFO - 'tests': ['tp5o']}, 15:03:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'tests': ['tp5o']}, 15:03:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'talos_options': ['--disable-e10s', 15:03:02 INFO - '--xperf_path', 15:03:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:03:02 INFO - 'tests': ['tp5n']}, 15:03:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:03:02 INFO - 'talos_options': ['--xperf_path', 15:03:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:03:02 INFO - 'tests': ['tp5n']}}, 15:03:02 INFO - 'talos.zip': {'path': '', 15:03:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:03:02 INFO - Running post-action listener: _resource_record_post_action 15:03:02 INFO - ##### 15:03:02 INFO - ##### Finished populate-webroot step (success) 15:03:02 INFO - ##### 15:03:02 INFO - ##### 15:03:02 INFO - ##### Running create-virtualenv step. 15:03:02 INFO - ##### 15:03:02 INFO - Running pre-action listener: _resource_record_pre_action 15:03:02 INFO - Running main action method: create_virtualenv 15:03:02 INFO - Creating virtualenv /builds/slave/test/build/venv 15:03:02 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 15:03:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:03:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:03:02 INFO - Using real prefix '/tools/python27' 15:03:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:03:02 INFO - Installing distribute.............................................................................................................................................................................................done. 15:03:05 INFO - Installing pip.................done. 15:03:05 INFO - Return code: 0 15:03:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:03:05 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')]} 15:03:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:05 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')]} 15:03:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:05 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 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:05 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 15:03:05 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 15:03:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:05 INFO - 'HOME': '/Users/cltbld', 15:03:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:05 INFO - 'LOGNAME': 'cltbld', 15:03:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:05 INFO - 'MOZ_NO_REMOTE': '1', 15:03:05 INFO - 'NO_EM_RESTART': '1', 15:03:05 INFO - 'PAGER': '/bin/cat', 15:03:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:05 INFO - 'PWD': '/builds/slave/test', 15:03:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:05 INFO - 'SHELL': '/bin/bash', 15:03:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:05 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:05 INFO - 'USER': 'cltbld', 15:03:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:05 INFO - 'XPC_FLAGS': '0x0', 15:03:05 INFO - 'XPC_SERVICE_NAME': '0', 15:03:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:06 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 15:03:06 INFO - Cleaning up... 15:03:06 INFO - Return code: 0 15:03:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:03:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:06 INFO - 'HOME': '/Users/cltbld', 15:03:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:06 INFO - 'LOGNAME': 'cltbld', 15:03:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:06 INFO - 'MOZ_NO_REMOTE': '1', 15:03:06 INFO - 'NO_EM_RESTART': '1', 15:03:06 INFO - 'PAGER': '/bin/cat', 15:03:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:06 INFO - 'PWD': '/builds/slave/test', 15:03:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:06 INFO - 'SHELL': '/bin/bash', 15:03:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:06 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:06 INFO - 'USER': 'cltbld', 15:03:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:06 INFO - 'XPC_FLAGS': '0x0', 15:03:06 INFO - 'XPC_SERVICE_NAME': '0', 15:03:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:06 INFO - Downloading/unpacking psutil>=0.7.1 15:03:06 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 15:03:06 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 15:03:06 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 15:03:06 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 15:03:06 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 15:03:06 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 15:03:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:03:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:03:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:03:09 INFO - Installing collected packages: psutil 15:03:09 INFO - Running setup.py install for psutil 15:03:09 INFO - building 'psutil._psutil_osx' extension 15:03:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:03:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:03:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:03:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:03:10 INFO - building 'psutil._psutil_posix' extension 15:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:03:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:03:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:03:10 INFO - ^ 15:03:10 INFO - 1 warning generated. 15:03:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:03:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:03:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:03:10 INFO - Successfully installed psutil 15:03:10 INFO - Cleaning up... 15:03:10 INFO - Return code: 0 15:03:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:03:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:03:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:10 INFO - 'HOME': '/Users/cltbld', 15:03:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:10 INFO - 'LOGNAME': 'cltbld', 15:03:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:10 INFO - 'MOZ_NO_REMOTE': '1', 15:03:10 INFO - 'NO_EM_RESTART': '1', 15:03:10 INFO - 'PAGER': '/bin/cat', 15:03:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:10 INFO - 'PWD': '/builds/slave/test', 15:03:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:10 INFO - 'SHELL': '/bin/bash', 15:03:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:10 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:10 INFO - 'USER': 'cltbld', 15:03:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:10 INFO - 'XPC_FLAGS': '0x0', 15:03:10 INFO - 'XPC_SERVICE_NAME': '0', 15:03:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 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 15:03:10 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 15:03:13 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:03:13 INFO - Installing collected packages: mozsystemmonitor 15:03:13 INFO - Running setup.py install for mozsystemmonitor 15:03:13 INFO - Successfully installed mozsystemmonitor 15:03:13 INFO - Cleaning up... 15:03:13 INFO - Return code: 0 15:03:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:03:13 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')]} 15:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:13 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')]} 15:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:13 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 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:13 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 15:03:13 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 15:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:13 INFO - 'HOME': '/Users/cltbld', 15:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:13 INFO - 'LOGNAME': 'cltbld', 15:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:13 INFO - 'MOZ_NO_REMOTE': '1', 15:03:13 INFO - 'NO_EM_RESTART': '1', 15:03:13 INFO - 'PAGER': '/bin/cat', 15:03:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:13 INFO - 'PWD': '/builds/slave/test', 15:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:13 INFO - 'SHELL': '/bin/bash', 15:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:13 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:13 INFO - 'USER': 'cltbld', 15:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:13 INFO - 'XPC_FLAGS': '0x0', 15:03:13 INFO - 'XPC_SERVICE_NAME': '0', 15:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:13 INFO - Downloading/unpacking blobuploader==1.2.4 15:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:16 INFO - Downloading blobuploader-1.2.4.tar.gz 15:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:03:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:03:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:17 INFO - Downloading docopt-0.6.1.tar.gz 15:03:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:03:17 INFO - Installing collected packages: blobuploader, requests, docopt 15:03:17 INFO - Running setup.py install for blobuploader 15:03:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:03:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:03:17 INFO - Running setup.py install for requests 15:03:18 INFO - Running setup.py install for docopt 15:03:18 INFO - Successfully installed blobuploader requests docopt 15:03:18 INFO - Cleaning up... 15:03:18 INFO - Return code: 0 15:03:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:18 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 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:18 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 15:03:18 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 15:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:18 INFO - 'HOME': '/Users/cltbld', 15:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:18 INFO - 'LOGNAME': 'cltbld', 15:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:18 INFO - 'MOZ_NO_REMOTE': '1', 15:03:18 INFO - 'NO_EM_RESTART': '1', 15:03:18 INFO - 'PAGER': '/bin/cat', 15:03:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:18 INFO - 'PWD': '/builds/slave/test', 15:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:18 INFO - 'SHELL': '/bin/bash', 15:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:18 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:18 INFO - 'USER': 'cltbld', 15:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:18 INFO - 'XPC_FLAGS': '0x0', 15:03:18 INFO - 'XPC_SERVICE_NAME': '0', 15:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:03:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-n4Lx8V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:03:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-aj0ux8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:03:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-HwPTWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:03:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:03:18 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-sEBrHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Zhg0gU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Z4JxFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Q6fGCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SfZwAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-1bgcjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-_hlJYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jBGBkx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-SNCGhs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:03:19 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Rez58o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:03:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-WPAaS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-bQ6Abg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:03:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-Kvcjlh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:03:20 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-BFBtDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:03:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:03:20 INFO - Running setup.py install for manifestparser 15:03:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:03:20 INFO - Running setup.py install for mozcrash 15:03:20 INFO - Running setup.py install for mozdebug 15:03:20 INFO - Running setup.py install for mozdevice 15:03:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Running setup.py install for mozfile 15:03:21 INFO - Running setup.py install for mozhttpd 15:03:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Running setup.py install for mozinfo 15:03:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Running setup.py install for mozInstall 15:03:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Running setup.py install for mozleak 15:03:21 INFO - Running setup.py install for mozlog 15:03:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:03:21 INFO - Running setup.py install for moznetwork 15:03:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:03:22 INFO - Running setup.py install for mozprocess 15:03:22 INFO - Running setup.py install for mozprofile 15:03:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:03:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:03:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:03:22 INFO - Running setup.py install for mozrunner 15:03:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:03:22 INFO - Running setup.py install for mozscreenshot 15:03:22 INFO - Running setup.py install for moztest 15:03:22 INFO - Running setup.py install for mozversion 15:03:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:03:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:03:22 INFO - Cleaning up... 15:03:22 INFO - Return code: 0 15:03:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03:22 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')]} 15:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:22 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')]} 15:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:22 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 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:22 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 15:03:22 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 15:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:22 INFO - 'HOME': '/Users/cltbld', 15:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:22 INFO - 'LOGNAME': 'cltbld', 15:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:22 INFO - 'MOZ_NO_REMOTE': '1', 15:03:22 INFO - 'NO_EM_RESTART': '1', 15:03:22 INFO - 'PAGER': '/bin/cat', 15:03:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:22 INFO - 'PWD': '/builds/slave/test', 15:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:22 INFO - 'SHELL': '/bin/bash', 15:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:22 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:22 INFO - 'USER': 'cltbld', 15:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:22 INFO - 'XPC_FLAGS': '0x0', 15:03:22 INFO - 'XPC_SERVICE_NAME': '0', 15:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-WI8mC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ckIJ7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-grTk6Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-gf8PVk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-9eihbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-qaf1eP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-bLvZwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:03:23 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)) 15:03:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:03:23 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ewKN2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:03: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)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-daHN1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:03: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)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-jNzCqG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-vQ0LdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:03:24 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)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-CR7w6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-EFP4dB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:03:24 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)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-ozMxn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-4q48ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:03:24 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)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-vcuRbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:03:24 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)) 15:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:03:24 INFO - Running setup.py (path:/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/pip-wrOJkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:27 INFO - Downloading blessings-1.6.tar.gz 15:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:03:27 INFO - Installing collected packages: blessings 15:03:27 INFO - Running setup.py install for blessings 15:03:27 INFO - Successfully installed blessings 15:03:27 INFO - Cleaning up... 15:03:27 INFO - Return code: 0 15:03:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:03:28 INFO - Reading from file tmpfile_stdout 15:03:28 INFO - Current package versions: 15:03:28 INFO - blessings == 1.6 15:03:28 INFO - blobuploader == 1.2.4 15:03:28 INFO - docopt == 0.6.1 15:03:28 INFO - manifestparser == 1.1 15:03:28 INFO - mozInstall == 1.12 15:03:28 INFO - mozcrash == 0.17 15:03:28 INFO - mozdebug == 0.1 15:03:28 INFO - mozdevice == 0.48 15:03:28 INFO - mozfile == 1.2 15:03:28 INFO - mozhttpd == 0.7 15:03:28 INFO - mozinfo == 0.9 15:03:28 INFO - mozleak == 0.1 15:03:28 INFO - mozlog == 3.1 15:03:28 INFO - moznetwork == 0.27 15:03:28 INFO - mozprocess == 0.22 15:03:28 INFO - mozprofile == 0.28 15:03:28 INFO - mozrunner == 6.11 15:03:28 INFO - mozscreenshot == 0.1 15:03:28 INFO - mozsystemmonitor == 0.0 15:03:28 INFO - moztest == 0.7 15:03:28 INFO - mozversion == 1.4 15:03:28 INFO - psutil == 3.1.1 15:03:28 INFO - requests == 1.2.3 15:03:28 INFO - wsgiref == 0.1.2 15:03:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:03: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')]} 15:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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')]} 15:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:28 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 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:28 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 15:03:28 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 15:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:28 INFO - 'HOME': '/Users/cltbld', 15:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:28 INFO - 'LOGNAME': 'cltbld', 15:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:28 INFO - 'MOZ_NO_REMOTE': '1', 15:03:28 INFO - 'NO_EM_RESTART': '1', 15:03:28 INFO - 'PAGER': '/bin/cat', 15:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:28 INFO - 'PWD': '/builds/slave/test', 15:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:28 INFO - 'SHELL': '/bin/bash', 15:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:28 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:28 INFO - 'USER': 'cltbld', 15:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:28 INFO - 'XPC_FLAGS': '0x0', 15:03:28 INFO - 'XPC_SERVICE_NAME': '0', 15:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03: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)) 15:03:28 INFO - Cleaning up... 15:03:28 INFO - Return code: 0 15:03:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:03: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')]} 15:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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')]} 15:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03: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 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03: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 15:03: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 15:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:28 INFO - 'HOME': '/Users/cltbld', 15:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:28 INFO - 'LOGNAME': 'cltbld', 15:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:28 INFO - 'MOZ_NO_REMOTE': '1', 15:03:28 INFO - 'NO_EM_RESTART': '1', 15:03:28 INFO - 'PAGER': '/bin/cat', 15:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:28 INFO - 'PWD': '/builds/slave/test', 15:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:28 INFO - 'SHELL': '/bin/bash', 15:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:28 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:28 INFO - 'USER': 'cltbld', 15:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:28 INFO - 'XPC_FLAGS': '0x0', 15:03:28 INFO - 'XPC_SERVICE_NAME': '0', 15:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:28 INFO - Downloading/unpacking jsonschema 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03: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 15:03:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:03:32 INFO - Downloading/unpacking functools32 (from jsonschema) 15:03:32 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 15:03:32 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 15:03:32 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 15:03:32 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 15:03:32 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 15:03:32 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 15:03:32 INFO - Downloading functools32-3.2.3-2.tar.gz 15:03:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:03:32 INFO - warning: no files found matching '*.txt' 15:03:32 INFO - no previously-included directories found matching 'build' 15:03:32 INFO - no previously-included directories found matching 'dist' 15:03:32 INFO - no previously-included directories found matching '.git*' 15:03:32 INFO - Installing collected packages: jsonschema, functools32 15:03:32 INFO - Running setup.py install for functools32 15:03:32 INFO - warning: no files found matching '*.txt' 15:03:32 INFO - no previously-included directories found matching 'build' 15:03:32 INFO - no previously-included directories found matching 'dist' 15:03:32 INFO - no previously-included directories found matching '.git*' 15:03:32 INFO - Successfully installed jsonschema functools32 15:03:32 INFO - Cleaning up... 15:03:32 INFO - Return code: 0 15:03:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:03:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101cb8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101cbe570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbc82d9bfd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 15:03:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 15:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 15:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:03:32 INFO - 'HOME': '/Users/cltbld', 15:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:03:32 INFO - 'LOGNAME': 'cltbld', 15:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:03:32 INFO - 'MOZ_NO_REMOTE': '1', 15:03:32 INFO - 'NO_EM_RESTART': '1', 15:03:32 INFO - 'PAGER': '/bin/cat', 15:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:03:32 INFO - 'PWD': '/builds/slave/test', 15:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:03:32 INFO - 'SHELL': '/bin/bash', 15:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:03:32 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:03:32 INFO - 'USER': 'cltbld', 15:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:03:32 INFO - 'XPC_FLAGS': '0x0', 15:03:32 INFO - 'XPC_SERVICE_NAME': '0', 15:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:03:33 INFO - Downloading/unpacking flake8 15:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:03:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:03:35 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 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 15:03:35 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 15:03:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:03:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:03:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:03:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:03:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:03:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:03:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:03:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:03:36 INFO - no previously-included directories found matching 'docs/_build' 15:03:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:03:36 INFO - Running setup.py install for pep8 15:03:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:03:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:03:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:03:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:03:36 INFO - no previously-included directories found matching 'docs/_build' 15:03:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:03:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:03:37 INFO - Cleaning up... 15:03:37 INFO - Return code: 0 15:03:37 INFO - Running post-action listener: _resource_record_post_action 15:03:37 INFO - Running post-action listener: _start_resource_monitoring 15:03:37 INFO - Starting resource monitoring. 15:03:37 INFO - ##### 15:03:37 INFO - ##### Finished create-virtualenv step (success) 15:03:37 INFO - ##### 15:03:37 INFO - ##### 15:03:37 INFO - ##### Running install step. 15:03:37 INFO - ##### 15:03:37 INFO - Running pre-action listener: _resource_record_pre_action 15:03:37 INFO - Running main action method: install 15:03:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:03:37 INFO - Reading from file tmpfile_stdout 15:03:37 INFO - Detecting whether we're running mozinstall >=1.0... 15:03:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:03:37 INFO - Reading from file tmpfile_stdout 15:03:37 INFO - Output received: 15:03:37 INFO - Usage: mozinstall [options] installer 15:03:37 INFO - Options: 15:03:37 INFO - -h, --help show this help message and exit 15:03:37 INFO - -d DEST, --destination=DEST 15:03:37 INFO - Directory to install application into. [default: 15:03:37 INFO - "/builds/slave/test"] 15:03:37 INFO - --app=APP Application being installed. [default: firefox] 15:03:37 INFO - mkdir: /builds/slave/test/build/application 15:03:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:04:11 INFO - Reading from file tmpfile_stdout 15:04:11 INFO - Output received: 15:04:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:04:11 INFO - Running post-action listener: _resource_record_post_action 15:04:11 INFO - ##### 15:04:11 INFO - ##### Finished install step (success) 15:04:11 INFO - ##### 15:04:11 INFO - ##### 15:04:11 INFO - ##### Running run-tests step. 15:04:11 INFO - ##### 15:04:11 INFO - Running pre-action listener: _resource_record_pre_action 15:04:11 INFO - Running main action method: run_tests 15:04:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:04:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:04:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:04:11 INFO - 2016-05-11 15:04:11.254 screenresolution[1638:7809] starting screenresolution argv=screenresolution get 15:04:11 INFO - 2016-05-11 15:04:11.257 screenresolution[1638:7809] Display 0: 1600x1200x32@60 15:04:11 INFO - 2016-05-11 15:04:11.262 screenresolution[1639:7811] starting screenresolution argv=screenresolution list 15:04:11 INFO - Available Modes on Display 0 15:04:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:04:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:04:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:04:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:04:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:04:11 INFO - Intel Iris: 15:04:11 INFO - Chipset Model: Intel Iris 15:04:11 INFO - Type: GPU 15:04:11 INFO - Bus: Built-In 15:04:11 INFO - VRAM (Dynamic, Max): 1536 MB 15:04:11 INFO - Vendor: Intel (0x8086) 15:04:11 INFO - Device ID: 0x0a2e 15:04:11 INFO - Revision ID: 0x0009 15:04:11 INFO - Displays: 15:04:11 INFO - Display: 15:04:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:04:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:04:11 INFO - Display Serial Number: 1600x1200 60 15:04:11 INFO - Main Display: Yes 15:04:11 INFO - Mirror: Off 15:04:11 INFO - Online: Yes 15:04:11 INFO - Rotation: Supported 15:04:11 INFO - Return code: 0 15:04:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:04:11 INFO - Python 2.7.3 15:04:11 INFO - Return code: 0 15:04:11 INFO - grabbing minidump binary from tooltool 15:04:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:04:11 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/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 0x101b5cd30>, '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 0x101980db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101b53b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:04:11 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:04:11 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:04:11 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:04:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:04:11 INFO - Return code: 0 15:04:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:04:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:04:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:04:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:04:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:04:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:04:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:04:12 INFO - Return code: 0 15:04:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 15:04:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0084 --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 15:04:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jjCDMRm0KF/Render', 15:04:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:04:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:04:12 INFO - 'HOME': '/Users/cltbld', 15:04:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:04:12 INFO - 'LOGNAME': 'cltbld', 15:04:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:04:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:04:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:04:12 INFO - 'MOZ_NO_REMOTE': '1', 15:04:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:04:12 INFO - 'NO_EM_RESTART': '1', 15:04:12 INFO - 'PAGER': '/bin/cat', 15:04:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:04:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:04:12 INFO - 'PWD': '/builds/slave/test', 15:04:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:04:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:04:12 INFO - 'SHELL': '/bin/bash', 15:04:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners', 15:04:12 INFO - 'TMPDIR': '/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/', 15:04:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:04:12 INFO - 'USER': 'cltbld', 15:04:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:04:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:04:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:04:12 INFO - 'XPC_FLAGS': '0x0', 15:04:12 INFO - 'XPC_SERVICE_NAME': '0', 15:04:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:04:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0084', '--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 15:04:12 INFO - mozversion application_buildid: 20160511140805 15:04:12 INFO - mozversion application_changeset: 6581bbe179b96e153cbc54335681cfc5c9195d62 15:04:12 INFO - mozversion application_display_name: Nightly 15:04:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:04:12 INFO - mozversion application_name: Firefox 15:04:12 INFO - mozversion application_remotingname: firefox 15:04:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:04:12 INFO - mozversion application_vendor: Mozilla 15:04:12 INFO - mozversion application_version: 49.0a1 15:04:12 INFO - mozversion platform_buildid: 20160511140805 15:04:12 INFO - mozversion platform_changeset: 6581bbe179b96e153cbc54335681cfc5c9195d62 15:04:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:04:12 INFO - mozversion platform_version: 49.0a1 15:04:12 INFO - using testdate: 1463004252 15:04:12 INFO - actual date: 1463004252 15:04:12 INFO - starting webserver on 'localhost:49277' 15:04:12 INFO - SUITE-START | Running 2 tests 15:04:12 INFO - TEST-START | dromaeo_css 15:04:12 INFO - operating with platform_type : mac_ 15:04:12 INFO - Initialising browser for dromaeo_css test... 15:04:12 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpxCfTpY/profile http://localhost:49277/getInfo.html) 15:04:15 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 15:04:15 INFO - PROCESS | 1657 | colorDepth:24 15:04:15 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 15:04:15 INFO - PROCESS | 1657 | __metrics 15:04:15 INFO - PROCESS | 1657 | 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" 15:04:15 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:04:16 INFO - TEST-INFO | 1657: exit 0 15:04:16 INFO - Browser initialized. 15:04:16 INFO - Running cycle 1/1 for dromaeo_css test... 15:04:16 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmpxCfTpY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:04:28 INFO - PROCESS | 1659 | RSS: Main: 238915584 15:04:28 INFO - PROCESS | 1659 | 15:05:19 INFO - PROCESS | 1659 | 1463004319486 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] 15:07:30 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 15:07:32 INFO - PROCESS | 1659 | RSS: Main: 367972352 15:07:32 INFO - PROCESS | 1659 | 15:10:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 15:10:35 INFO - PROCESS | 1659 | RSS: Main: 374939648 15:10:35 INFO - PROCESS | 1659 | 15:13:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 15:13:38 INFO - PROCESS | 1659 | RSS: Main: 370122752 15:13:38 INFO - PROCESS | 1659 | 15:16:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 15:16:41 INFO - PROCESS | 1659 | RSS: Main: 369090560 15:16:41 INFO - PROCESS | 1659 | 15:19:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 15:19:45 INFO - PROCESS | 1659 | RSS: Main: 338522112 15:19:45 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 15:22:48 INFO - PROCESS | 1659 | RSS: Main: 376950784 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | __start_tp_report 15:22:48 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 15:22:48 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 15:22:48 INFO - PROCESS | 1659 | |i|pagename|runs| 15:22:48 INFO - PROCESS | 1659 | |0;dojo.html;3427.0918206675237;3440.8107554084513;3460.377132116219;3469.288795796862;3507.6316986716392;5705.058665320223;5721.799737009205;5762.250907382045;5795.971020144904;5805.332386775517;4878.707277563349;5758.712064396785;5778.422157784219;5880.117982302657;5886.587938844283;5106.846794596168;5125.538701516863;5190.558802501791;5208.947910520884;5287.154055351145;5310.203469479589;5338.092524270884;5340.893182136355;5358.4641535846495;5393.703346315951;5148.536631703165;5206.140986737184;5213.7393130343335;5235;5274.894502109974;5238.371395432534;5240.449752775941;5241.056609810233;5258.447862974371;5269.657472264291;5147.382314122329;5177.715225662587;5199.194124910645;5207.062728708831;5212.452692467312;5576.1356989666665;5611.877624475122;5614.691191984439;5759.107338362561;5775.162601422812;6195.81412557624;6239.594426362272;6264.373562643699;6272.87454250917;6275.780347687809;6170.444659980623;6196.256449226122;6246.15676883624;6305.684715764193;6323.114763933053;5846.093855452412;5917.467427931507;5918.437748413894;5928.110783382484;5942.554308426886;6307.873842523216;6315.052742088632;6320.083587879732;6320.62076275425;6365.8726825464155;6053.697315134225;6078.848028799315;6211.689415529296;6225.906611400833;6240.937590624036;6223.408776166257;6278.277998030194;6294.433500984842;6324.778632747923;6333.176687030748;6254.468370188494;6257.937420625826;6270.059491076284;6275.560710750204;6283.183186185766;6110.746072516537;6181.96909015452;6245.687715614201;6309.305976342599;6311.400416960381;4922.360093187935;4949.208126699711;4963.379577552806;4974.801007959641;5012.423571289275;4434.423524941802;4451.265541185733;4454.7549884756;4465.687401881837;4504.2793153095345;5568.832935011875;5586;5602.355729091125;5701.458361455655;5734.627249228787;6208.627482351073;6229.626222426669;6238.376162383725;6283.214598175228;6289.276733175652;5746.942530574641;5747.568932330092;5754.798582049608;5757.078867381283;5795.768169273266;5381.704006279614;5407;5417.485338892799;5427.3758517770175;5433.320834895638;6233.096201050822;6271.811845644638;6281.96859015702;6337.746490140343;6338.429541341301;6322.178116844781;6327.557071004985;6337.746490140343;6349.460295874902;6356.936430635635;6347.206599175103;6366.4588509977175;6419.197600299963;6435.1312572803945;6436.806895793134;6017.097435384728;6066.423689749467;6145.231846019247;6163.075538669235;6190.907136393048;6311.747530098928;6316.557840951089;6318.810435686937;6322.841928951813;6350.047492876106;6130.63216207029;6132.41742034506;6144.907826382519;6158.3841615838055;6176;5912.379200184091;5940.762369505344;5996.010658241516;6107.9921812900375;6109.327973922954;4611.377464042312;5845.3277873045145;5867.295924489089;5888.116782482491;5968.641881487125;6147.647517546133;6164.599301045418;6169.290531588925;6175.011998080286;6176.011838105882;6006.639601623917;6055;6055.666938318434;6056.818295451144;6111.144439778345 15:22:48 INFO - PROCESS | 1659 | |1;ext.html;495.9950400495995;516.3726072821519;518.5151882989408;540.964053836902;543.6765124750773;30276.697233027662;31043.689563104363;32177.83911080444;32190.195245118826;32192.03423897287;32348.67651323486;45550.31674524885;46713;46909.530904690946;46958.530414695844;9673.903260967405;9817.803643927116;9820.06709362613;9835.754106147331;9867.457289849055;8564.229219370256;9624.615015399375;9732.610695572168;9736.123748862601;9742.805143897152;8600.655973761042;11765.411729413538;11945.641630751092;11972.521099156023;12130.757384852297;11667.708307292301;11752.47113879869;11755.764884702343;11760.235584686969;11763.764724705501;8605.913940860637;11351.978321951065;11450;11592.072634189244;11863.644090677293;32743.345133097442;39970.20059598821;40360.39459408111;40375;40425.98935026619;3120.563121163039;3250.0574833298315;3276.19733165376;3545.6808887200277;4090.8159132838796;27961.580576291373;28545.715442805125;29724.70275297241;29825.105246842628;30978.225544361347;2783.6937936827135;2788.986055069732;2794.930126746827;2904.43363544109;2947.3957838643028;491726;505509;505543;506053.99999999633;507272;57158.42841571531;68499.97250041254;68890.31109688838;70094;70142.64928675324;75009.62495187599;75235;75312.62343688247;75466.62266688742;75476;12319.938400307941;13927.930360348337;14371.281435928371;17429.738553921703;17443.389481368093;11512.712182195344;13109.60671179866;13128.934355328352;13171.47314107444;13192.934035329954;15262.542123736306;18250.90874545619;20777.376678699362;21293.46766330823;21482.677759833616;7845.1762564931005;8360.498370097814;8839.524933018765;8855.64577416909;8946.776330591787;912.3112050402036;933.5332333833084;994.6021591363512;1032.8690084357615;1048.9003544663244;28344.858275708488;28461.430771384872;29922.700772992426;30556.84721576378;30728.231794205323;1100.5652767156928;1129.5312445335258;1163.33689796815;1170.3329102411544;1246.962591122268;13722.01030850521;15192.860535459884;15442.0734755915;15679.039431956357;15707.293171807296;665649;676203;677322;677766;679727.60136199;47383.76308118437;60852;61160;61462.692686536284;64884.729034483564;1299.2789002103923;1434.5911415246812;1462.444271176946;1485.5617592810147;1489.2181604657642;29424.558631620115;30355.544666830458;30448;31166.130032197234;31460.84269578729;47720;50064.749676251384;51106.74446627743;51204;51239.487605123475;518207;520153;520647;524155;524713;34835.65164348324;35576.822115889256;41223.793881030404;42275.73172804821;42310.73067807971;45922.540774593166;46061.30908036449;46575.76712116418;47397.52602473931;47422;36570.63429365672;36965.44551831671;37497.81251093727;37537.2680407233;38403.42394864135;36924.076898077765;37406.5037398501;37878.8106059468;38098.857034289016;38432.807835960644;36311.27377452489;36775.448368275036;36862;37201.627983719816;37622.81188594149;31348.062600217196;32518.398528118167;32798.34403311968;32809.835950820096;32847.50728739023;23998.88000559986;24305.39236519101;24322.90546925428;24400.389990250387;25106 15:22:48 INFO - PROCESS | 1659 | |2;jquery.html;373.3895081541682;381.38977635782743;386.4975531808649;389.2234991192571;410.6837734944093;13831.861681383183;14525.273736313182;21697.240596579104;22041.00815463304;22091.889540552296;10672.893271067287;11967.46146423411;12374.628761137157;12385.876141238585;12389.814152787692;5122.641415100945;5629.155626655994;5649.858753531174;5650.886982260352;5655.57583181261;3887.941680874789;4132.4421203137645;4143.295639741236;4150.418941348214;4152.335626299792;6783.932160678404;6846.931530684704;6850.657467126649;6852.314768523158;6860.108185935821;6058.182145410379;6162.26052873658;6167.414095660905;6814.62519561424;6842.965785171092;3783.754055986352;4022.9195416091516;5969.791057313017;5970.552208584373;5982.970085149546;11856.40717964107;11955.163138580303;13075.084744067919;13095.083344165912;13108.27904465254;2068.214078650118;2122.479992401856;2190.3210004898533;2261.457250259926;2272.261515007629;10342.534585943651;10364.274500784948;10391.688249352455;12976.35118244084;14720.558383248519;1774.6007148391639;1849.870509064366;1858.8141185881439;1866.3467786274696;1881.7768450507142;58756.70621646907;99744;111809;119071.4046429771;121028;11513.597024104116;11629.767404651859;11705;11707.297562146128;11808.409579521162;15165.165915874511;15185.6203594911;15207.771883421758;15252.084874907541;15281.770773438408;8538.274246688978;8702.564871756387;8735.91264087351;8815.206631403205;8841.248493877963;8629.784255393666;8655.697050603325;8661.437006594551;8770.4737715736;8776.385653004356;4577.702449340783;4661.203278033153;4666.1834179019215;4676.672632915731;4731.384919960452;1833.2117189608596;1914.1386376130797;1976.3280484635293;1980.9504762381055;2006.9197232110844;387.7905930797338;397.8846134620985;424.6602717825789;758.4349659503595;843.215809297347;11457.71355716097;11893.464794084288;11968.82046769299;11998;12064.758704825854;429.22524842658675;433.22669035770997;442.6348262683299;447.2910436957459;449.5818888433788;5027.497250274943;6033.607815491979;6128.908066379008;6137.478314343331;6144.477719393902;603714;613457;636259;636357;644521;70127.94808077883;112603.4369828162;126824.46351072927;132203.67796321915;132779.33610331887;459.1162013124721;462.5351521720582;469.59380136181954;470.0504979940501;470.1114892852443;9026.774330641787;11191.552337906718;11251.774964500828;11401.372924489233;11428.771424571296;50263.74868125636;51440.188421966035;51501;51523.742381287855;52033.21950170826;73063;77659.83510247238;80174;81405.371892563;83191.5840420794;8049.356051515982;8157.918420815716;8235.711750088718;8401;8485.024222214524;11582.189246752647;12563.87436125627;12915.870841291842;12922.676933076747;13133.343332833128;10893.673189804318;10917.399543024892;11190.384528850991;11192.440377980905;11523.942380288381;9217.400868943498;10594.523246453773;10643.042126208678;11033.393163375771;11062.834057489305;11226.66320010401;11481.425928703364;11539.423028848356;11563.901429364198;11744.236624619372;8264.752057438287;8276.420650554644;8379.916200837913;8446.704365347183;8728.389012769045;11141;11209;11335;11384.48769805344;11608.709782255173 15:22:48 INFO - PROCESS | 1659 | |3;mootools.html;1344.7579435701575;1429.31392931393;1589.268936289307;1772.9024903630295;1980.8019198080194;7736.613169341532;8198.959005204973;8201.548914809691;8204.753857384274;8204.958975205116;8406.747797566082;8728.389012769108;8735.082816304292;8822.382433229677;8828.426152300097;2358.375030616892;2429.2955043037587;2431.0397393029743;2434.294054724128;2439.597466418039;2380.75002124777;2381.7261014983237;2418.987905060481;2420.2739178246543;2424;2381.3213234228247;2392.2583998960376;2408.915687950922;2417.069428270111;2418.2503423938633;2374.536965291769;2385.4155731845635;2424.127314166907;2424.5393375258645;2426.1872272788637;2406.9638955415508;2411.782939535433;2437.7805997460314;2442.072012635205;2442.755724427561;8732.78168045804;8790.692325768632;8795.164459376349;8800.16398442147;8804.207621314048;5204.583633309327;5253.08071087562;5371.785128594891;5372.409035006146;5409.972950135263;3392.1350055735875;3587.8206089695673;3638.0904773806546;3693.464447655102;3693.5567731872347;1047.1099565369452;1060.9469526523642;1070.2294348069377;1544.7760074789092;1601.49552890839;11766.705832354259;11791.941040294743;11845.466953987092;11852.051835853112;11869.99105076061;4551.499335073139;4631.282151266559;4705.764711764398;4711.7879695413785;4715.74063426508;9303;9319.254459643213;9331.766705832408;9332.253419726405;9346.485943273185;5351.3043304370185;5354.785808567692;5360.946390536044;5363.624546281801;5366.302380690563;4337.197618440536;4420.823167073281;4630.259158534618;4939.876503087452;4945.604351651859;3752.137008488063;3778.4899038629983;3816.9618303816605;3854.094287842355;3882.475865758143;1158.0851127609094;1184.1875545579248;1187.2994932989404;1199.2025303173466;1203.0676225924803;841.1840514700731;877.7981064355106;915.4873270968279;928.7353104365224;934.5700977550267;4375.496817865923;5228.895422091537;5713.857153571194;5909.3795151508475;5913.70431478433;811.3955103447969;968.704545113747;975.9219262458986;979.1823827104448;995.6764051683232;3771.7548359356556;3854.8265328060306;3857.9421308680394;3896.883093507199;3923.9803800980812;10430.947845260725;10931.398773067558;10968.97074041305;10974.231803773659;10980.176486763365;10500.579976800842;10847.945760271306;10903.073238774634;11019.72450688723;11076.667699969013;8148.918510814935;8213.917860821435;8255.711050113337;8342.958285208535;8443.873341899876;5676.545876329967;5739.512141467855;5740.712964351849;5821;5963.28440587115;9591.184749296388;9934.602615895283;9950.154236889946;10080.445575493419;10212.489375531342;8844.690435834715;9621.845378554619;10349.275550711378;10357.326773759682;10383.428911409945;6887.552309099892;7151.928480715126;7502.924970750441;7642.388608911163;7651;8393.95803020981;8412.957935210285;8421;8504.44721093127;8507.106753791011;7476.775696729107;7509.324160825552;7530.359919406912;7630.427717921067;7636.160022397421;7309.671064801991;7393.926060739539;7402.4448166386655;7545.584992825227;7559.357454616419;6754.155730533684;7132.928670713226;7146.964265178641;7154.67803948834;7157.391621712004;4750.239961606127;4944;4983.352164218773;4988.476209997898;5023.924641130459;4939.827106051271;4981.178105612534;5038.546530812245;5087.592992560512;5242.266082748494 15:22:48 INFO - PROCESS | 1659 | |4;prototype.html;301.55972280470513;315.657511599914;317.26711296904153;324.06184097039073;330.3080047300904;4513.774311284435;5275.393329767068;5384.734587371964;5443.2107344435035;5452.781888724451;4361.73829570226;4465.933011004838;5386.579522003527;5396.43337449568;5440.700761458118;3875.5736868944396;3950.545687245975;4031;4086.8978275543054;4190.601892820177;3620.5604675558193;3754.793486358249;3997.560268370477;4047.6761859051353;4106.733062350938;3265.2489927316733;3539.610642829287;3718.7025037996896;4056;4074.490688663917;3774.3960966245268;3861.903452413684;3906.433567132778;3931.646151846319;4068.2677118118727;3081.245094951751;3126.889954066319;3527.5061491391225;3528.576570811519;3528.6943830782775;4868.342773725572;5138.563222126124;5148.794048238066;5227.752066611356;5255.583870484178;3725.9813700931595;4469.5530446955045;4591.487546743084;4635.3742244797195;4662.580367766917;4916.58209052231;5527.226188333637;5556.583256255756;5587.441255874421;5603.562450334118;3253.040353095819;3519.8736404350616;3604.567451905788;3899.512560929884;3991.4611527443712;5550.69471179085;5935.495482884004;5983.401659834025;6036.486898613623;6055.031195008777;3622.619624939396;3873.69010479161;3892.163184915257;3897;3899.395593682984;3332.966670333314;3950.6049395060263;3951.209758048402;3958;3960.890950533855;4389.824407023747;4574.88562785933;4629.537046295343;4654.9044885151;4665.160271151225;4687.99208170245;5300.8001679303425;5674.00704876645;5720.256366672389;5749.568782341341;1200.8619008813923;1349.3253373313344;1353.708952575201;1354.7561438940888;1366.685662297677;736.9792836920903;794.1105961323354;814.983700326002;828.8437629506805;830.8712149616819;4755.239161734106;4984.981082661528;5049.596032317406;5139.8458046258675;5205.401378841406;4665.393498845128;5674.631148975303;5788.934682153116;5836.299644042657;5844.503217226498;4653.395058642355;4777.450593181759;4966.553010229024;5276.913005891596;5304.6817190967895;3037.2255074955747;3068.98465507671;3073.492873675863;3078.153507785377;3084.290613158986;5415.972920135374;6052.243469566304;6058.878822423615;6084.665343406155;6089.878202435926;5119.948800512022;5439.062906030778;5945.019071853096;5959.851003724942;5984.431479009487;4903.205173051327;5646.576506761927;6005.549583781234;6063.787767428119;6107.358727333655;4327.65378769704;5820.30156381237;5827.280952119219;5837.153612726046;5840.94159058404;5541.085720856014;5804.303483581997;5816.185733997159;5830.329512105993;5849.502792262705;5252.632315737862;5826.8928903508195;6094.7257373417415;6138.907916381348;6154.323024467394;3142.214446388403;3701.759385639925;3942.5663177051074;4097.815598298143;4105.7741824198765;4815.783289751899;5015.473375295687;5330.786768529216;5691.573132015033;5782.392848750989;5270.393904701008;5617.983142053451;5646.3788983212635;5739.368669446274;5744.368119506935;5224.138017227115;5284.449432597581;5459.317585301837;5606.355269143937;5652.915206271991;4850.53919877611;5449.291592093003;5466.480684334982;5496.3236212955435;5549.833504994856;5272.288241087557;5382.4886635769535;5569.860753481195;5591.23233999173;5614.803481878278;4605;4630.86107416778;4639.279896618774;4843.75781210931;5535.474129957647 15:22:48 INFO - PROCESS | 1659 | |5;yui.html;636.8535236895514;662.4236913884918;672.7847088931541;689.7344522358891;710.4281053751731;14673.926630366846;15342.925995180341;15361.231938403063;15384.692306153884;15409.768853467207;13875.098118622282;14200.573982780506;14277.714445711079;14509.9843010989;14547.490837820678;934.0332755597957;942.7313215733517;952.4618590496382;952.7379970508131;953.9983017831285;938.249400479617;941.9152276295166;944.0559440559441;946.0775743649955;946.2430055955243;1000.2797985450501;1001.8096561653299;1001.9549120289568;1004.437514991607;1007.8286691262466;881.6473410635765;986.4574484033752;989.8713167288242;992.0525897767651;994.0109590956972;747.8653842308382;748.6818102306498;750.6697053296587;753.6608526163249;755.527795128045;11881.5841445549;11950.581729639507;13318.400671969785;13374.13068150567;13422.664433389244;10905.400202988832;11149;11162.609308674157;11185.608503702413;11549.595764148298;9086.818263634756;9338.9533052335;9344.579493922789;9384.5307734613;9392.060793920622;3437.1407148212948;3438.329525742481;3620.2035552178477;3632.219072899339;3736.327461057009;78454;78624;78753.81869271965;78897;79092;9275.257979361635;9326.440413575072;9328.580213890391;9574.664886728931;9599.088086631758;16542.42101526441;20626;20711.689324660143;20753.962301884847;20754.792452075286;7347.669354879044;7354.926450735531;7376.815579610446;7450.441216908754;7468.066491688539;3812.7902965336616;3958.148997965451;4091.713580049368;4201.6008479194425;4203.26442872496;3520.6479352065103;3611.530501034849;3616.5660120785674;3619.7828130312264;3621.5292012038794;633.9651319177398;646.4214527997416;648.0797267879528;653.5033374635217;668.5153263883743;447.5524475524476;556.7689408895344;603.6378173096107;604.2627993847586;607.6870411737958;7444.478886477895;8182.304504117098;8193.221643943816;8355.874661879956;8489.915100849035;592.2034863109111;605.42787066222;618.5762752514541;621.8196722950385;624.1324558863183;3747.887563373103;3896.2207558488417;3918.8824335269987;3975.960240397559;4110.938335924963;90671;92979;94522;96259.51870240604;96585.51707241559;73699.52600948059;76557.85163222556;76859;77104.61447692839;78959;56709.71645141748;71625.2837471629;71764.64117679483;72050.63974680199;73499.26500734924;7566.962165189138;7573.523162983165;8322.916770832215;8399.370047246359;8416.074231834371;43592;43944;44142.33786493269;44730.77634611806;44788.77605612081;57063;57228.71385643045;60439.39560604338;61715;63550;10758.677239682822;10999.780004399707;11070;11072.833907491555;11083.334999900031;11723.179377443497;11811.291322520676;11866.169368144147;12252.988047808765;12350.07374446902;11229.887701122883;11275;11572.537098515964;11576.536938522364;11612.477438515456;11323.547058117583;11331.886681133083;11549.538018479167;11559.537618495166;11582.36297003673;11073.83389249178;11106.722331941766;11408.030317423112;11534.884651153381;11588.072954163816;9212;9334.20659243972;9435.339526233098;9563.282753793354;9755.512224388895;9360.251179905456;9381.906180938377;9396.812063758549;9438.339316247797;9478.478683672463 15:22:48 INFO - PROCESS | 1659 | __end_tp_report 15:22:48 INFO - PROCESS | 1659 | __start_cc_report 15:22:48 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1386 15:22:48 INFO - PROCESS | 1659 | __end_cc_report 15:22:48 INFO - PROCESS | 1659 | __startTimestamp1463005368977__endTimestamp 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | ------- Summary: start ------- 15:22:48 INFO - PROCESS | 1659 | Number of tests: 6 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5769.09 Median:5996.01 stddev:642.92 (10.7%) stddev-sans-first:618.08 15:22:48 INFO - PROCESS | 1659 | Values: 3427.1 3440.8 3460.4 3469.3 3507.6 5705.1 5721.8 5762.3 5796.0 5805.3 4878.7 5758.7 5778.4 5880.1 5886.6 5106.8 5125.5 5190.6 5208.9 5287.2 5310.2 5338.1 5340.9 5358.5 5393.7 5148.5 5206.1 5213.7 5235.0 5274.9 5238.4 5240.4 5241.1 5258.4 5269.7 5147.4 5177.7 5199.2 5207.1 5212.5 5576.1 5611.9 5614.7 5759.1 5775.2 6195.8 6239.6 6264.4 6272.9 6275.8 6170.4 6196.3 6246.2 6305.7 6323.1 5846.1 5917.5 5918.4 5928.1 5942.6 6307.9 6315.1 6320.1 6320.6 6365.9 6053.7 6078.8 6211.7 6225.9 6240.9 6223.4 6278.3 6294.4 6324.8 6333.2 6254.5 6257.9 6270.1 6275.6 6283.2 6110.7 6182.0 6245.7 6309.3 6311.4 4922.4 4949.2 4963.4 4974.8 5012.4 4434.4 4451.3 4454.8 4465.7 4504.3 5568.8 5586.0 5602.4 5701.5 5734.6 6208.6 6229.6 6238.4 6283.2 6289.3 5746.9 5747.6 5754.8 5757.1 5795.8 5381.7 5407.0 5417.5 5427.4 5433.3 6233.1 6271.8 6282.0 6337.7 6338.4 6322.2 6327.6 6337.7 6349.5 6356.9 6347.2 6366.5 6419.2 6435.1 6436.8 6017.1 6066.4 6145.2 6163.1 6190.9 6311.7 6316.6 6318.8 6322.8 6350.0 6130.6 6132.4 6144.9 6158.4 6176.0 5912.4 5940.8 5996.0 6108.0 6109.3 4611.4 5845.3 5867.3 5888.1 5968.6 6147.6 6164.6 6169.3 6175.0 6176.0 6006.6 6055.0 6055.7 6056.8 6111.1 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:70696.55 Median:36818.72 stddev:152823.46 (415.1%) stddev-sans-first:153161.28 15:22:48 INFO - PROCESS | 1659 | Values: 496.0 516.4 518.5 541.0 543.7 30276.7 31043.7 32177.8 32190.2 32192.0 32348.7 45550.3 46713.0 46909.5 46958.5 9673.9 9817.8 9820.1 9835.8 9867.5 8564.2 9624.6 9732.6 9736.1 9742.8 8600.7 11765.4 11945.6 11972.5 12130.8 11667.7 11752.5 11755.8 11760.2 11763.8 8605.9 11352.0 11450.0 11592.1 11863.6 32743.3 39970.2 40360.4 40375.0 40426.0 3120.6 3250.1 3276.2 3545.7 4090.8 27961.6 28545.7 29724.7 29825.1 30978.2 2783.7 2789.0 2794.9 2904.4 2947.4 491726.0 505509.0 505543.0 506054.0 507272.0 57158.4 68500.0 68890.3 70094.0 70142.6 75009.6 75235.0 75312.6 75466.6 75476.0 12319.9 13927.9 14371.3 17429.7 17443.4 11512.7 13109.6 13128.9 13171.5 13192.9 15262.5 18250.9 20777.4 21293.5 21482.7 7845.2 8360.5 8839.5 8855.6 8946.8 912.3 933.5 994.6 1032.9 1048.9 28344.9 28461.4 29922.7 30556.8 30728.2 1100.6 1129.5 1163.3 1170.3 1247.0 13722.0 15192.9 15442.1 15679.0 15707.3 665649.0 676203.0 677322.0 677766.0 679727.6 47383.8 60852.0 61160.0 61462.7 64884.7 1299.3 1434.6 1462.4 1485.6 1489.2 29424.6 30355.5 30448.0 31166.1 31460.8 47720.0 50064.7 51106.7 51204.0 51239.5 518207.0 520153.0 520647.0 524155.0 524713.0 34835.7 35576.8 41223.8 42275.7 42310.7 45922.5 46061.3 46575.8 47397.5 47422.0 36570.6 36965.4 37497.8 37537.3 38403.4 36924.1 37406.5 37878.8 38098.9 38432.8 36311.3 36775.4 36862.0 37201.6 37622.8 31348.1 32518.4 32798.3 32809.8 32847.5 23998.9 24305.4 24322.9 24400.4 25106.0 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:33798.67 Median:2138.58 stddev:104146.33 (4869.9%) stddev-sans-first:104408.24 15:22:48 INFO - PROCESS | 1659 | Values: 373.4 381.4 386.5 389.2 410.7 13831.9 14525.3 21697.2 22041.0 22091.9 10672.9 11967.5 12374.6 12385.9 12389.8 5122.6 5629.2 5649.9 5650.9 5655.6 3887.9 4132.4 4143.3 4150.4 4152.3 6783.9 6846.9 6850.7 6852.3 6860.1 6058.2 6162.3 6167.4 6814.6 6843.0 3783.8 4022.9 5969.8 5970.6 5983.0 11856.4 11955.2 13075.1 13095.1 13108.3 2068.2 2122.5 2190.3 2261.5 2272.3 10342.5 10364.3 10391.7 12976.4 14720.6 1774.6 1849.9 1858.8 1866.3 1881.8 58756.7 99744.0 111809.0 119071.4 121028.0 11513.6 11629.8 11705.0 11707.3 11808.4 15165.2 15185.6 15207.8 15252.1 15281.8 8538.3 8702.6 8735.9 8815.2 8841.2 8629.8 8655.7 8661.4 8770.5 8776.4 4577.7 4661.2 4666.2 4676.7 4731.4 1833.2 1914.1 1976.3 1981.0 2006.9 387.8 397.9 424.7 758.4 843.2 11457.7 11893.5 11968.8 11998.0 12064.8 429.2 433.2 442.6 447.3 449.6 5027.5 6033.6 6128.9 6137.5 6144.5 603714.0 613457.0 636259.0 636357.0 644521.0 70127.9 112603.4 126824.5 132203.7 132779.3 459.1 462.5 469.6 470.1 470.1 9026.8 11191.6 11251.8 11401.4 11428.8 50263.7 51440.2 51501.0 51523.7 52033.2 73063.0 77659.8 80174.0 81405.4 83191.6 8049.4 8157.9 8235.7 8401.0 8485.0 11582.2 12563.9 12915.9 12922.7 13133.3 10893.7 10917.4 11190.4 11192.4 11523.9 9217.4 10594.5 10643.0 11033.4 11062.8 11226.7 11481.4 11539.4 11563.9 11744.2 8264.8 8276.4 8379.9 8446.7 8728.4 11141.0 11209.0 11335.0 11384.5 11608.7 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5693.88 Median:4985.91 stddev:3179.80 (63.8%) stddev-sans-first:3171.92 15:22:48 INFO - PROCESS | 1659 | Values: 1344.8 1429.3 1589.3 1772.9 1980.8 7736.6 8199.0 8201.5 8204.8 8205.0 8406.7 8728.4 8735.1 8822.4 8828.4 2358.4 2429.3 2431.0 2434.3 2439.6 2380.8 2381.7 2419.0 2420.3 2424.0 2381.3 2392.3 2408.9 2417.1 2418.3 2374.5 2385.4 2424.1 2424.5 2426.2 2407.0 2411.8 2437.8 2442.1 2442.8 8732.8 8790.7 8795.2 8800.2 8804.2 5204.6 5253.1 5371.8 5372.4 5410.0 3392.1 3587.8 3638.1 3693.5 3693.6 1047.1 1060.9 1070.2 1544.8 1601.5 11766.7 11791.9 11845.5 11852.1 11870.0 4551.5 4631.3 4705.8 4711.8 4715.7 9303.0 9319.3 9331.8 9332.3 9346.5 5351.3 5354.8 5360.9 5363.6 5366.3 4337.2 4420.8 4630.3 4939.9 4945.6 3752.1 3778.5 3817.0 3854.1 3882.5 1158.1 1184.2 1187.3 1199.2 1203.1 841.2 877.8 915.5 928.7 934.6 4375.5 5228.9 5713.9 5909.4 5913.7 811.4 968.7 975.9 979.2 995.7 3771.8 3854.8 3857.9 3896.9 3924.0 10430.9 10931.4 10969.0 10974.2 10980.2 10500.6 10847.9 10903.1 11019.7 11076.7 8148.9 8213.9 8255.7 8343.0 8443.9 5676.5 5739.5 5740.7 5821.0 5963.3 9591.2 9934.6 9950.2 10080.4 10212.5 8844.7 9621.8 10349.3 10357.3 10383.4 6887.6 7151.9 7502.9 7642.4 7651.0 8394.0 8413.0 8421.0 8504.4 8507.1 7476.8 7509.3 7530.4 7630.4 7636.2 7309.7 7393.9 7402.4 7545.6 7559.4 6754.2 7132.9 7147.0 7154.7 7157.4 4750.2 4944.0 4983.4 4988.5 5023.9 4939.8 4981.2 5038.5 5087.6 5242.3 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4525.54 Median:5032.53 stddev:1417.15 (28.2%) stddev-sans-first:1385.21 15:22:48 INFO - PROCESS | 1659 | Values: 301.6 315.7 317.3 324.1 330.3 4513.8 5275.4 5384.7 5443.2 5452.8 4361.7 4465.9 5386.6 5396.4 5440.7 3875.6 3950.5 4031.0 4086.9 4190.6 3620.6 3754.8 3997.6 4047.7 4106.7 3265.2 3539.6 3718.7 4056.0 4074.5 3774.4 3861.9 3906.4 3931.6 4068.3 3081.2 3126.9 3527.5 3528.6 3528.7 4868.3 5138.6 5148.8 5227.8 5255.6 3726.0 4469.6 4591.5 4635.4 4662.6 4916.6 5527.2 5556.6 5587.4 5603.6 3253.0 3519.9 3604.6 3899.5 3991.5 5550.7 5935.5 5983.4 6036.5 6055.0 3622.6 3873.7 3892.2 3897.0 3899.4 3333.0 3950.6 3951.2 3958.0 3960.9 4389.8 4574.9 4629.5 4654.9 4665.2 4688.0 5300.8 5674.0 5720.3 5749.6 1200.9 1349.3 1353.7 1354.8 1366.7 737.0 794.1 815.0 828.8 830.9 4755.2 4985.0 5049.6 5139.8 5205.4 4665.4 5674.6 5788.9 5836.3 5844.5 4653.4 4777.5 4966.6 5276.9 5304.7 3037.2 3069.0 3073.5 3078.2 3084.3 5416.0 6052.2 6058.9 6084.7 6089.9 5119.9 5439.1 5945.0 5959.9 5984.4 4903.2 5646.6 6005.5 6063.8 6107.4 4327.7 5820.3 5827.3 5837.2 5840.9 5541.1 5804.3 5816.2 5830.3 5849.5 5252.6 5826.9 6094.7 6138.9 6154.3 3142.2 3701.8 3942.6 4097.8 4105.8 4815.8 5015.5 5330.8 5691.6 5782.4 5270.4 5618.0 5646.4 5739.4 5744.4 5224.1 5284.4 5459.3 5606.4 5652.9 4850.5 5449.3 5466.5 5496.3 5549.8 5272.3 5382.5 5569.9 5591.2 5614.8 4605.0 4630.9 4639.3 4843.8 5535.5 15:22:48 INFO - PROCESS | 1659 | 15:22:48 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17696.34 Median:31161.34 stddev:25041.75 (80.4%) stddev-sans-first:25079.24 15:22:49 INFO - PROCESS | 1659 | Values: 636.9 662.4 672.8 689.7 710.4 14673.9 15342.9 15361.2 15384.7 15409.8 13875.1 14200.6 14277.7 14510.0 14547.5 934.0 942.7 952.5 952.7 954.0 938.2 941.9 944.1 946.1 946.2 1000.3 1001.8 1002.0 1004.4 1007.8 881.6 986.5 989.9 992.1 994.0 747.9 748.7 750.7 753.7 755.5 11881.6 11950.6 13318.4 13374.1 13422.7 10905.4 11149.0 11162.6 11185.6 11549.6 9086.8 9339.0 9344.6 9384.5 9392.1 3437.1 3438.3 3620.2 3632.2 3736.3 78454.0 78624.0 78753.8 78897.0 79092.0 9275.3 9326.4 9328.6 9574.7 9599.1 16542.4 20626.0 20711.7 20754.0 20754.8 7347.7 7354.9 7376.8 7450.4 7468.1 3812.8 3958.1 4091.7 4201.6 4203.3 3520.6 3611.5 3616.6 3619.8 3621.5 634.0 646.4 648.1 653.5 668.5 447.6 556.8 603.6 604.3 607.7 7444.5 8182.3 8193.2 8355.9 8489.9 592.2 605.4 618.6 621.8 624.1 3747.9 3896.2 3918.9 3976.0 4110.9 90671.0 92979.0 94522.0 96259.5 96585.5 73699.5 76557.9 76859.0 77104.6 78959.0 56709.7 71625.3 71764.6 72050.6 73499.3 7567.0 7573.5 8322.9 8399.4 8416.1 43592.0 43944.0 44142.3 44730.8 44788.8 57063.0 57228.7 60439.4 61715.0 63550.0 10758.7 10999.8 11070.0 11072.8 11083.3 11723.2 11811.3 11866.2 12253.0 12350.1 11229.9 11275.0 11572.5 11576.5 11612.5 11323.5 11331.9 11549.5 11559.5 11582.4 11073.8 11106.7 11408.0 11534.9 11588.1 9212.0 9334.2 9435.3 9563.3 9755.5 9360.3 9381.9 9396.8 9438.3 9478.5 15:22:49 INFO - PROCESS | 1659 | -------- Summary: end -------- 15:22:49 INFO - PROCESS | 1659 | 15:22:49 INFO - TEST-INFO | 1659: exit 0 15:22:49 INFO - TEST-OK | dromaeo_css | took 1116594ms 15:22:49 INFO - TEST-START | kraken 15:22:49 INFO - operating with platform_type : mac_ 15:22:49 INFO - Initialising browser for kraken test... 15:22:49 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmprFaOyN/profile http://localhost:49277/getInfo.html) 15:22:51 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 15:22:51 INFO - PROCESS | 1669 | colorDepth:24 15:22:51 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 15:22:51 INFO - PROCESS | 1669 | __metrics 15:22:51 INFO - PROCESS | 1669 | 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" 15:22:51 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:22:52 INFO - TEST-INFO | 1669: exit 0 15:22:52 INFO - Browser initialized. 15:22:52 INFO - Running cycle 1/1 for kraken test... 15:22:52 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/tmprFaOyN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:23:04 INFO - PROCESS | 1671 | RSS: Main: 239149056 15:23:04 INFO - PROCESS | 1671 | 15:23:07 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 15:23:08 INFO - PROCESS | 1671 | RSS: Main: 286842880 15:23:08 INFO - PROCESS | 1671 | 15:23:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 15:23:11 INFO - PROCESS | 1671 | RSS: Main: 295563264 15:23:11 INFO - PROCESS | 1671 | 15:23:15 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 15:23:15 INFO - PROCESS | 1671 | RSS: Main: 386752512 15:23:15 INFO - PROCESS | 1671 | 15:23:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 15:23:18 INFO - PROCESS | 1671 | RSS: Main: 285786112 15:23:18 INFO - PROCESS | 1671 | 15:23:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:23:21 INFO - PROCESS | 1671 | RSS: Main: 288485376 15:23:21 INFO - PROCESS | 1671 | 15:23:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 15:23:25 INFO - PROCESS | 1671 | RSS: Main: 326496256 15:23:25 INFO - PROCESS | 1671 | 15:23:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 15:23:28 INFO - PROCESS | 1671 | RSS: Main: 329924608 15:23:28 INFO - PROCESS | 1671 | 15:23:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 15:23:32 INFO - PROCESS | 1671 | RSS: Main: 329695232 15:23:32 INFO - PROCESS | 1671 | 15:23:34 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:23:34 INFO - PROCESS | 1671 | RSS: Main: 315658240 15:23:34 INFO - PROCESS | 1671 | 15:23:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:23:37 INFO - PROCESS | 1671 | RSS: Main: 321777664 15:23:37 INFO - PROCESS | 1671 | 15:23:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:23:40 INFO - PROCESS | 1671 | RSS: Main: 324042752 15:23:40 INFO - PROCESS | 1671 | 15:23:43 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:23:44 INFO - PROCESS | 1671 | RSS: Main: 329015296 15:23:44 INFO - PROCESS | 1671 | 15:23:46 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:23:47 INFO - PROCESS | 1671 | RSS: Main: 326688768 15:23:47 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 15:23:49 INFO - PROCESS | 1671 | RSS: Main: 372809728 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | __start_tp_report 15:23:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 15:23:49 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 15:23:49 INFO - PROCESS | 1671 | |i|pagename|runs| 15:23:49 INFO - PROCESS | 1671 | |0;ai-astar;113;106;112;112;112;114;110;113;113;112 15:23:49 INFO - PROCESS | 1671 | |1;audio-beat-detection;174;116;143;195;124;139;143;105;168;139 15:23:49 INFO - PROCESS | 1671 | |2;audio-dft;183;186;182;181;184;184;224;186;181;184 15:23:49 INFO - PROCESS | 1671 | |3;audio-fft;77;73;57;125;61;85;81;58;114;55 15:23:49 INFO - PROCESS | 1671 | |4;audio-oscillator;92;100;135;78;83;77;119;80;77;102 15:23:49 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;92;85;87;90;89;90;88;85;87;84 15:23:49 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;93;93;93;93;93;92;93;93;93 15:23:49 INFO - PROCESS | 1671 | |7;imaging-desaturate;83;84;87;85;84;101;134;100;83;84 15:23:49 INFO - PROCESS | 1671 | |8;json-parse-financial;58;58;59;58;57;59;58;124;60;56 15:23:49 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;63;62;72;73;69;69;67;67;68;70 15:23:49 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;85;85;86;84;85;84;80;102;108;106 15:23:49 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;150;152;142;136;136;135;135;135;225;140 15:23:49 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;168;167;169;165;168;166;163;171;230;187 15:23:49 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;65;64;60;66;80;83;86;80;66;65 15:23:49 INFO - PROCESS | 1671 | __end_tp_report 15:23:49 INFO - PROCESS | 1671 | __start_cc_report 15:23:49 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2225 15:23:49 INFO - PROCESS | 1671 | __end_cc_report 15:23:49 INFO - PROCESS | 1671 | __startTimestamp1463005429750__endTimestamp 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | ------- Summary: start ------- 15:23:49 INFO - PROCESS | 1671 | Number of tests: 14 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.50 stddev:2.26 (2.0%) stddev-sans-first:2.35 15:23:49 INFO - PROCESS | 1671 | Values: 113.0 106.0 112.0 112.0 112.0 114.0 110.0 113.0 113.0 112.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:143.00 stddev:27.55 (19.3%) stddev-sans-first:27.09 15:23:49 INFO - PROCESS | 1671 | Values: 174.0 116.0 143.0 195.0 124.0 139.0 143.0 105.0 168.0 139.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:187.50 Median:184.00 stddev:12.95 (7.0%) stddev-sans-first:13.63 15:23:49 INFO - PROCESS | 1671 | Values: 183.0 186.0 182.0 181.0 184.0 184.0 224.0 186.0 181.0 184.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:78.60 Median:67.00 stddev:24.14 (36.0%) stddev-sans-first:25.60 15:23:49 INFO - PROCESS | 1671 | Values: 77.0 73.0 57.0 125.0 61.0 85.0 81.0 58.0 114.0 55.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:77.50 stddev:19.89 (25.7%) stddev-sans-first:21.08 15:23:49 INFO - PROCESS | 1671 | Values: 92.0 100.0 135.0 78.0 83.0 77.0 119.0 80.0 77.0 102.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:88.50 stddev:2.58 (2.9%) stddev-sans-first:2.22 15:23:49 INFO - PROCESS | 1671 | Values: 92.0 85.0 87.0 90.0 89.0 90.0 88.0 85.0 87.0 84.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:92.90 Median:93.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:23:49 INFO - PROCESS | 1671 | Values: 93.0 93.0 93.0 93.0 93.0 93.0 92.0 93.0 93.0 93.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:92.50 Median:84.00 stddev:16.11 (19.2%) stddev-sans-first:16.71 15:23:49 INFO - PROCESS | 1671 | Values: 83.0 84.0 87.0 85.0 84.0 101.0 134.0 100.0 83.0 84.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:64.70 Median:58.00 stddev:20.86 (36.0%) stddev-sans-first:21.99 15:23:49 INFO - PROCESS | 1671 | Values: 58.0 58.0 59.0 58.0 57.0 59.0 58.0 124.0 60.0 56.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:69.00 stddev:3.50 (5.1%) stddev-sans-first:3.21 15:23:49 INFO - PROCESS | 1671 | Values: 63.0 62.0 72.0 73.0 69.0 69.0 67.0 67.0 68.0 70.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:90.50 Median:84.50 stddev:10.46 (12.4%) stddev-sans-first:10.90 15:23:49 INFO - PROCESS | 1671 | Values: 85.0 85.0 86.0 84.0 85.0 84.0 80.0 102.0 108.0 106.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:148.60 Median:141.00 stddev:27.57 (19.6%) stddev-sans-first:29.24 15:23:49 INFO - PROCESS | 1671 | Values: 150.0 152.0 142.0 136.0 136.0 135.0 135.0 135.0 225.0 140.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.40 Median:168.50 stddev:20.29 (12.0%) stddev-sans-first:21.35 15:23:49 INFO - PROCESS | 1671 | Values: 168.0 167.0 169.0 165.0 168.0 166.0 163.0 171.0 230.0 187.0 15:23:49 INFO - PROCESS | 1671 | 15:23:49 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:73.00 stddev:9.55 (13.1%) stddev-sans-first:9.83 15:23:49 INFO - PROCESS | 1671 | Values: 65.0 64.0 60.0 66.0 80.0 83.0 86.0 80.0 66.0 65.0 15:23:49 INFO - PROCESS | 1671 | -------- Summary: end -------- 15:23:49 INFO - PROCESS | 1671 | 15:23:50 INFO - TEST-INFO | 1671: exit 0 15:23:50 INFO - TEST-OK | kraken | took 61068ms 15:23:50 INFO - SUITE-END | took 1177s 15:23:50 INFO - Completed test suite (00:19:37) 15:23:50 INFO - javascript benchmark 15:23:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3427.0918206675237, 3440.8107554084513, 3460.377132116219, 3469.288795796862, 3507.6316986716392, 5705.058665320223, 5721.799737009205, 5762.250907382045, 5795.971020144904, 5805.332386775517, 4878.707277563349, 5758.712064396785, 5778.422157784219, 5880.117982302657, 5886.587938844283, 5106.846794596168, 5125.538701516863, 5190.558802501791, 5208.947910520884, 5287.154055351145, 5310.203469479589, 5338.092524270884, 5340.893182136355, 5358.4641535846495, 5393.703346315951, 5148.536631703165, 5206.140986737184, 5213.7393130343335, 5235.0, 5274.894502109974, 5238.371395432534, 5240.449752775941, 5241.056609810233, 5258.447862974371, 5269.657472264291, 5147.382314122329, 5177.715225662587, 5199.194124910645, 5207.062728708831, 5212.452692467312, 5576.1356989666665, 5611.877624475122, 5614.691191984439, 5759.107338362561, 5775.162601422812, 6195.81412557624, 6239.594426362272, 6264.373562643699, 6272.87454250917, 6275.780347687809, 6170.444659980623, 6196.256449226122, 6246.15676883624, 6305.684715764193, 6323.114763933053, 5846.093855452412, 5917.467427931507, 5918.437748413894, 5928.110783382484, 5942.554308426886, 6307.873842523216, 6315.052742088632, 6320.083587879732, 6320.62076275425, 6365.8726825464155, 6053.697315134225, 6078.848028799315, 6211.689415529296, 6225.906611400833, 6240.937590624036, 6223.408776166257, 6278.277998030194, 6294.433500984842, 6324.778632747923, 6333.176687030748, 6254.468370188494, 6257.937420625826, 6270.059491076284, 6275.560710750204, 6283.183186185766, 6110.746072516537, 6181.96909015452, 6245.687715614201, 6309.305976342599, 6311.400416960381, 4922.360093187935, 4949.208126699711, 4963.379577552806, 4974.801007959641, 5012.423571289275, 4434.423524941802, 4451.265541185733, 4454.7549884756, 4465.687401881837, 4504.2793153095345, 5568.832935011875, 5586.0, 5602.355729091125, 5701.458361455655, 5734.627249228787, 6208.627482351073, 6229.626222426669, 6238.376162383725, 6283.214598175228, 6289.276733175652, 5746.942530574641, 5747.568932330092, 5754.798582049608, 5757.078867381283, 5795.768169273266, 5381.704006279614, 5407.0, 5417.485338892799, 5427.3758517770175, 5433.320834895638, 6233.096201050822, 6271.811845644638, 6281.96859015702, 6337.746490140343, 6338.429541341301, 6322.178116844781, 6327.557071004985, 6337.746490140343, 6349.460295874902, 6356.936430635635, 6347.206599175103, 6366.4588509977175, 6419.197600299963, 6435.1312572803945, 6436.806895793134, 6017.097435384728, 6066.423689749467, 6145.231846019247, 6163.075538669235, 6190.907136393048, 6311.747530098928, 6316.557840951089, 6318.810435686937, 6322.841928951813, 6350.047492876106, 6130.63216207029, 6132.41742034506, 6144.907826382519, 6158.3841615838055, 6176.0, 5912.379200184091, 5940.762369505344, 5996.010658241516, 6107.9921812900375, 6109.327973922954, 4611.377464042312, 5845.3277873045145, 5867.295924489089, 5888.116782482491, 5968.641881487125, 6147.647517546133, 6164.599301045418, 6169.290531588925, 6175.011998080286, 6176.011838105882, 6006.639601623917, 6055.0, 6055.666938318434, 6056.818295451144, 6111.144439778345], "value": 5728.741174239827, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.9950400495995, 516.3726072821519, 518.5151882989408, 540.964053836902, 543.6765124750773, 30276.697233027662, 31043.689563104363, 32177.83911080444, 32190.195245118826, 32192.03423897287, 32348.67651323486, 45550.31674524885, 46713.0, 46909.530904690946, 46958.530414695844, 9673.903260967405, 9817.803643927116, 9820.06709362613, 9835.754106147331, 9867.457289849055, 8564.229219370256, 9624.615015399375, 9732.610695572168, 9736.123748862601, 9742.805143897152, 8600.655973761042, 11765.411729413538, 11945.641630751092, 11972.521099156023, 12130.757384852297, 11667.708307292301, 11752.47113879869, 11755.764884702343, 11760.235584686969, 11763.764724705501, 8605.913940860637, 11351.978321951065, 11450.0, 11592.072634189244, 11863.644090677293, 32743.345133097442, 39970.20059598821, 40360.39459408111, 40375.0, 40425.98935026619, 3120.563121163039, 3250.0574833298315, 3276.19733165376, 3545.6808887200277, 4090.8159132838796, 27961.580576291373, 28545.715442805125, 29724.70275297241, 29825.105246842628, 30978.225544361347, 2783.6937936827135, 2788.986055069732, 2794.930126746827, 2904.43363544109, 2947.3957838643028, 491726.0, 505509.0, 505543.0, 506053.99999999633, 507272.0, 57158.42841571531, 68499.97250041254, 68890.31109688838, 70094.0, 70142.64928675324, 75009.62495187599, 75235.0, 75312.62343688247, 75466.62266688742, 75476.0, 12319.938400307941, 13927.930360348337, 14371.281435928371, 17429.738553921703, 17443.389481368093, 11512.712182195344, 13109.60671179866, 13128.934355328352, 13171.47314107444, 13192.934035329954, 15262.542123736306, 18250.90874545619, 20777.376678699362, 21293.46766330823, 21482.677759833616, 7845.1762564931005, 8360.498370097814, 8839.524933018765, 8855.64577416909, 8946.776330591787, 912.3112050402036, 933.5332333833084, 994.6021591363512, 1032.8690084357615, 1048.9003544663244, 28344.858275708488, 28461.430771384872, 29922.700772992426, 30556.84721576378, 30728.231794205323, 1100.5652767156928, 1129.5312445335258, 1163.33689796815, 1170.3329102411544, 1246.962591122268, 13722.01030850521, 15192.860535459884, 15442.0734755915, 15679.039431956357, 15707.293171807296, 665649.0, 676203.0, 677322.0, 677766.0, 679727.60136199, 47383.76308118437, 60852.0, 61160.0, 61462.692686536284, 64884.729034483564, 1299.2789002103923, 1434.5911415246812, 1462.444271176946, 1485.5617592810147, 1489.2181604657642, 29424.558631620115, 30355.544666830458, 30448.0, 31166.130032197234, 31460.84269578729, 47720.0, 50064.749676251384, 51106.74446627743, 51204.0, 51239.487605123475, 518207.0, 520153.0, 520647.0, 524155.0, 524713.0, 34835.65164348324, 35576.822115889256, 41223.793881030404, 42275.73172804821, 42310.73067807971, 45922.540774593166, 46061.30908036449, 46575.76712116418, 47397.52602473931, 47422.0, 36570.63429365672, 36965.44551831671, 37497.81251093727, 37537.2680407233, 38403.42394864135, 36924.076898077765, 37406.5037398501, 37878.8106059468, 38098.857034289016, 38432.807835960644, 36311.27377452489, 36775.448368275036, 36862.0, 37201.627983719816, 37622.81188594149, 31348.062600217196, 32518.398528118167, 32798.34403311968, 32809.835950820096, 32847.50728739023, 23998.88000559986, 24305.39236519101, 24322.90546925428, 24400.389990250387, 25106.0], "value": 20475.66474902795, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.3895081541682, 381.38977635782743, 386.4975531808649, 389.2234991192571, 410.6837734944093, 13831.861681383183, 14525.273736313182, 21697.240596579104, 22041.00815463304, 22091.889540552296, 10672.893271067287, 11967.46146423411, 12374.628761137157, 12385.876141238585, 12389.814152787692, 5122.641415100945, 5629.155626655994, 5649.858753531174, 5650.886982260352, 5655.57583181261, 3887.941680874789, 4132.4421203137645, 4143.295639741236, 4150.418941348214, 4152.335626299792, 6783.932160678404, 6846.931530684704, 6850.657467126649, 6852.314768523158, 6860.108185935821, 6058.182145410379, 6162.26052873658, 6167.414095660905, 6814.62519561424, 6842.965785171092, 3783.754055986352, 4022.9195416091516, 5969.791057313017, 5970.552208584373, 5982.970085149546, 11856.40717964107, 11955.163138580303, 13075.084744067919, 13095.083344165912, 13108.27904465254, 2068.214078650118, 2122.479992401856, 2190.3210004898533, 2261.457250259926, 2272.261515007629, 10342.534585943651, 10364.274500784948, 10391.688249352455, 12976.35118244084, 14720.558383248519, 1774.6007148391639, 1849.870509064366, 1858.8141185881439, 1866.3467786274696, 1881.7768450507142, 58756.70621646907, 99744.0, 111809.0, 119071.4046429771, 121028.0, 11513.597024104116, 11629.767404651859, 11705.0, 11707.297562146128, 11808.409579521162, 15165.165915874511, 15185.6203594911, 15207.771883421758, 15252.084874907541, 15281.770773438408, 8538.274246688978, 8702.564871756387, 8735.91264087351, 8815.206631403205, 8841.248493877963, 8629.784255393666, 8655.697050603325, 8661.437006594551, 8770.4737715736, 8776.385653004356, 4577.702449340783, 4661.203278033153, 4666.1834179019215, 4676.672632915731, 4731.384919960452, 1833.2117189608596, 1914.1386376130797, 1976.3280484635293, 1980.9504762381055, 2006.9197232110844, 387.7905930797338, 397.8846134620985, 424.6602717825789, 758.4349659503595, 843.215809297347, 11457.71355716097, 11893.464794084288, 11968.82046769299, 11998.0, 12064.758704825854, 429.22524842658675, 433.22669035770997, 442.6348262683299, 447.2910436957459, 449.5818888433788, 5027.497250274943, 6033.607815491979, 6128.908066379008, 6137.478314343331, 6144.477719393902, 603714.0, 613457.0, 636259.0, 636357.0, 644521.0, 70127.94808077883, 112603.4369828162, 126824.46351072927, 132203.67796321915, 132779.33610331887, 459.1162013124721, 462.5351521720582, 469.59380136181954, 470.0504979940501, 470.1114892852443, 9026.774330641787, 11191.552337906718, 11251.774964500828, 11401.372924489233, 11428.771424571296, 50263.74868125636, 51440.188421966035, 51501.0, 51523.742381287855, 52033.21950170826, 73063.0, 77659.83510247238, 80174.0, 81405.371892563, 83191.5840420794, 8049.356051515982, 8157.918420815716, 8235.711750088718, 8401.0, 8485.024222214524, 11582.189246752647, 12563.87436125627, 12915.870841291842, 12922.676933076747, 13133.343332833128, 10893.673189804318, 10917.399543024892, 11190.384528850991, 11192.440377980905, 11523.942380288381, 9217.400868943498, 10594.523246453773, 10643.042126208678, 11033.393163375771, 11062.834057489305, 11226.66320010401, 11481.425928703364, 11539.423028848356, 11563.901429364198, 11744.236624619372, 8264.752057438287, 8276.420650554644, 8379.916200837913, 8446.704365347183, 8728.389012769045, 11141.0, 11209.0, 11335.0, 11384.48769805344, 11608.709782255173], "value": 8288.27433898271, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.7579435701575, 1429.31392931393, 1589.268936289307, 1772.9024903630295, 1980.8019198080194, 7736.613169341532, 8198.959005204973, 8201.548914809691, 8204.753857384274, 8204.958975205116, 8406.747797566082, 8728.389012769108, 8735.082816304292, 8822.382433229677, 8828.426152300097, 2358.375030616892, 2429.2955043037587, 2431.0397393029743, 2434.294054724128, 2439.597466418039, 2380.75002124777, 2381.7261014983237, 2418.987905060481, 2420.2739178246543, 2424.0, 2381.3213234228247, 2392.2583998960376, 2408.915687950922, 2417.069428270111, 2418.2503423938633, 2374.536965291769, 2385.4155731845635, 2424.127314166907, 2424.5393375258645, 2426.1872272788637, 2406.9638955415508, 2411.782939535433, 2437.7805997460314, 2442.072012635205, 2442.755724427561, 8732.78168045804, 8790.692325768632, 8795.164459376349, 8800.16398442147, 8804.207621314048, 5204.583633309327, 5253.08071087562, 5371.785128594891, 5372.409035006146, 5409.972950135263, 3392.1350055735875, 3587.8206089695673, 3638.0904773806546, 3693.464447655102, 3693.5567731872347, 1047.1099565369452, 1060.9469526523642, 1070.2294348069377, 1544.7760074789092, 1601.49552890839, 11766.705832354259, 11791.941040294743, 11845.466953987092, 11852.051835853112, 11869.99105076061, 4551.499335073139, 4631.282151266559, 4705.764711764398, 4711.7879695413785, 4715.74063426508, 9303.0, 9319.254459643213, 9331.766705832408, 9332.253419726405, 9346.485943273185, 5351.3043304370185, 5354.785808567692, 5360.946390536044, 5363.624546281801, 5366.302380690563, 4337.197618440536, 4420.823167073281, 4630.259158534618, 4939.876503087452, 4945.604351651859, 3752.137008488063, 3778.4899038629983, 3816.9618303816605, 3854.094287842355, 3882.475865758143, 1158.0851127609094, 1184.1875545579248, 1187.2994932989404, 1199.2025303173466, 1203.0676225924803, 841.1840514700731, 877.7981064355106, 915.4873270968279, 928.7353104365224, 934.5700977550267, 4375.496817865923, 5228.895422091537, 5713.857153571194, 5909.3795151508475, 5913.70431478433, 811.3955103447969, 968.704545113747, 975.9219262458986, 979.1823827104448, 995.6764051683232, 3771.7548359356556, 3854.8265328060306, 3857.9421308680394, 3896.883093507199, 3923.9803800980812, 10430.947845260725, 10931.398773067558, 10968.97074041305, 10974.231803773659, 10980.176486763365, 10500.579976800842, 10847.945760271306, 10903.073238774634, 11019.72450688723, 11076.667699969013, 8148.918510814935, 8213.917860821435, 8255.711050113337, 8342.958285208535, 8443.873341899876, 5676.545876329967, 5739.512141467855, 5740.712964351849, 5821.0, 5963.28440587115, 9591.184749296388, 9934.602615895283, 9950.154236889946, 10080.445575493419, 10212.489375531342, 8844.690435834715, 9621.845378554619, 10349.275550711378, 10357.326773759682, 10383.428911409945, 6887.552309099892, 7151.928480715126, 7502.924970750441, 7642.388608911163, 7651.0, 8393.95803020981, 8412.957935210285, 8421.0, 8504.44721093127, 8507.106753791011, 7476.775696729107, 7509.324160825552, 7530.359919406912, 7630.427717921067, 7636.160022397421, 7309.671064801991, 7393.926060739539, 7402.4448166386655, 7545.584992825227, 7559.357454616419, 6754.155730533684, 7132.928670713226, 7146.964265178641, 7154.67803948834, 7157.391621712004, 4750.239961606127, 4944.0, 4983.352164218773, 4988.476209997898, 5023.924641130459, 4939.827106051271, 4981.178105612534, 5038.546530812245, 5087.592992560512, 5242.266082748494], "value": 4598.482446734909, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.55972280470513, 315.657511599914, 317.26711296904153, 324.06184097039073, 330.3080047300904, 4513.774311284435, 5275.393329767068, 5384.734587371964, 5443.2107344435035, 5452.781888724451, 4361.73829570226, 4465.933011004838, 5386.579522003527, 5396.43337449568, 5440.700761458118, 3875.5736868944396, 3950.545687245975, 4031.0, 4086.8978275543054, 4190.601892820177, 3620.5604675558193, 3754.793486358249, 3997.560268370477, 4047.6761859051353, 4106.733062350938, 3265.2489927316733, 3539.610642829287, 3718.7025037996896, 4056.0, 4074.490688663917, 3774.3960966245268, 3861.903452413684, 3906.433567132778, 3931.646151846319, 4068.2677118118727, 3081.245094951751, 3126.889954066319, 3527.5061491391225, 3528.576570811519, 3528.6943830782775, 4868.342773725572, 5138.563222126124, 5148.794048238066, 5227.752066611356, 5255.583870484178, 3725.9813700931595, 4469.5530446955045, 4591.487546743084, 4635.3742244797195, 4662.580367766917, 4916.58209052231, 5527.226188333637, 5556.583256255756, 5587.441255874421, 5603.562450334118, 3253.040353095819, 3519.8736404350616, 3604.567451905788, 3899.512560929884, 3991.4611527443712, 5550.69471179085, 5935.495482884004, 5983.401659834025, 6036.486898613623, 6055.031195008777, 3622.619624939396, 3873.69010479161, 3892.163184915257, 3897.0, 3899.395593682984, 3332.966670333314, 3950.6049395060263, 3951.209758048402, 3958.0, 3960.890950533855, 4389.824407023747, 4574.88562785933, 4629.537046295343, 4654.9044885151, 4665.160271151225, 4687.99208170245, 5300.8001679303425, 5674.00704876645, 5720.256366672389, 5749.568782341341, 1200.8619008813923, 1349.3253373313344, 1353.708952575201, 1354.7561438940888, 1366.685662297677, 736.9792836920903, 794.1105961323354, 814.983700326002, 828.8437629506805, 830.8712149616819, 4755.239161734106, 4984.981082661528, 5049.596032317406, 5139.8458046258675, 5205.401378841406, 4665.393498845128, 5674.631148975303, 5788.934682153116, 5836.299644042657, 5844.503217226498, 4653.395058642355, 4777.450593181759, 4966.553010229024, 5276.913005891596, 5304.6817190967895, 3037.2255074955747, 3068.98465507671, 3073.492873675863, 3078.153507785377, 3084.290613158986, 5415.972920135374, 6052.243469566304, 6058.878822423615, 6084.665343406155, 6089.878202435926, 5119.948800512022, 5439.062906030778, 5945.019071853096, 5959.851003724942, 5984.431479009487, 4903.205173051327, 5646.576506761927, 6005.549583781234, 6063.787767428119, 6107.358727333655, 4327.65378769704, 5820.30156381237, 5827.280952119219, 5837.153612726046, 5840.94159058404, 5541.085720856014, 5804.303483581997, 5816.185733997159, 5830.329512105993, 5849.502792262705, 5252.632315737862, 5826.8928903508195, 6094.7257373417415, 6138.907916381348, 6154.323024467394, 3142.214446388403, 3701.759385639925, 3942.5663177051074, 4097.815598298143, 4105.7741824198765, 4815.783289751899, 5015.473375295687, 5330.786768529216, 5691.573132015033, 5782.392848750989, 5270.393904701008, 5617.983142053451, 5646.3788983212635, 5739.368669446274, 5744.368119506935, 5224.138017227115, 5284.449432597581, 5459.317585301837, 5606.355269143937, 5652.915206271991, 4850.53919877611, 5449.291592093003, 5466.480684334982, 5496.3236212955435, 5549.833504994856, 5272.288241087557, 5382.4886635769535, 5569.860753481195, 5591.23233999173, 5614.803481878278, 4605.0, 4630.86107416778, 4639.279896618774, 4843.75781210931, 5535.474129957647], "value": 4073.9542497524835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [636.8535236895514, 662.4236913884918, 672.7847088931541, 689.7344522358891, 710.4281053751731, 14673.926630366846, 15342.925995180341, 15361.231938403063, 15384.692306153884, 15409.768853467207, 13875.098118622282, 14200.573982780506, 14277.714445711079, 14509.9843010989, 14547.490837820678, 934.0332755597957, 942.7313215733517, 952.4618590496382, 952.7379970508131, 953.9983017831285, 938.249400479617, 941.9152276295166, 944.0559440559441, 946.0775743649955, 946.2430055955243, 1000.2797985450501, 1001.8096561653299, 1001.9549120289568, 1004.437514991607, 1007.8286691262466, 881.6473410635765, 986.4574484033752, 989.8713167288242, 992.0525897767651, 994.0109590956972, 747.8653842308382, 748.6818102306498, 750.6697053296587, 753.6608526163249, 755.527795128045, 11881.5841445549, 11950.581729639507, 13318.400671969785, 13374.13068150567, 13422.664433389244, 10905.400202988832, 11149.0, 11162.609308674157, 11185.608503702413, 11549.595764148298, 9086.818263634756, 9338.9533052335, 9344.579493922789, 9384.5307734613, 9392.060793920622, 3437.1407148212948, 3438.329525742481, 3620.2035552178477, 3632.219072899339, 3736.327461057009, 78454.0, 78624.0, 78753.81869271965, 78897.0, 79092.0, 9275.257979361635, 9326.440413575072, 9328.580213890391, 9574.664886728931, 9599.088086631758, 16542.42101526441, 20626.0, 20711.689324660143, 20753.962301884847, 20754.792452075286, 7347.669354879044, 7354.926450735531, 7376.815579610446, 7450.441216908754, 7468.066491688539, 3812.7902965336616, 3958.148997965451, 4091.713580049368, 4201.6008479194425, 4203.26442872496, 3520.6479352065103, 3611.530501034849, 3616.5660120785674, 3619.7828130312264, 3621.5292012038794, 633.9651319177398, 646.4214527997416, 648.0797267879528, 653.5033374635217, 668.5153263883743, 447.5524475524476, 556.7689408895344, 603.6378173096107, 604.2627993847586, 607.6870411737958, 7444.478886477895, 8182.304504117098, 8193.221643943816, 8355.874661879956, 8489.915100849035, 592.2034863109111, 605.42787066222, 618.5762752514541, 621.8196722950385, 624.1324558863183, 3747.887563373103, 3896.2207558488417, 3918.8824335269987, 3975.960240397559, 4110.938335924963, 90671.0, 92979.0, 94522.0, 96259.51870240604, 96585.51707241559, 73699.52600948059, 76557.85163222556, 76859.0, 77104.61447692839, 78959.0, 56709.71645141748, 71625.2837471629, 71764.64117679483, 72050.63974680199, 73499.26500734924, 7566.962165189138, 7573.523162983165, 8322.916770832215, 8399.370047246359, 8416.074231834371, 43592.0, 43944.0, 44142.33786493269, 44730.77634611806, 44788.77605612081, 57063.0, 57228.71385643045, 60439.39560604338, 61715.0, 63550.0, 10758.677239682822, 10999.780004399707, 11070.0, 11072.833907491555, 11083.334999900031, 11723.179377443497, 11811.291322520676, 11866.169368144147, 12252.988047808765, 12350.07374446902, 11229.887701122883, 11275.0, 11572.537098515964, 11576.536938522364, 11612.477438515456, 11323.547058117583, 11331.886681133083, 11549.538018479167, 11559.537618495166, 11582.36297003673, 11073.83389249178, 11106.722331941766, 11408.030317423112, 11534.884651153381, 11588.072954163816, 9212.0, 9334.20659243972, 9435.339526233098, 9563.282753793354, 9755.512224388895, 9360.251179905456, 9381.906180938377, 9396.812063758549, 9438.339316247797, 9478.478683672463], "value": 6809.4642707065395, "unit": "score"}], "name": "dromaeo_css", "value": 7061.956419509787, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 112.0, 112.0, 114.0, 110.0, 113.0, 113.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 116.0, 143.0, 195.0, 124.0, 139.0, 143.0, 105.0, 168.0, 139.0], "value": 144.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 182.0, 181.0, 184.0, 184.0, 224.0, 186.0, 181.0, 184.0], "value": 187.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 57.0, 125.0, 61.0, 85.0, 81.0, 58.0, 114.0, 55.0], "value": 78.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 100.0, 135.0, 78.0, 83.0, 77.0, 119.0, 80.0, 77.0, 102.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 85.0, 87.0, 90.0, 89.0, 90.0, 88.0, 85.0, 87.0, 84.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 93.0], "value": 92.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 87.0, 85.0, 84.0, 101.0, 134.0, 100.0, 83.0, 84.0], "value": 92.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 58.0, 57.0, 59.0, 58.0, 124.0, 60.0, 56.0], "value": 64.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 72.0, 73.0, 69.0, 69.0, 67.0, 67.0, 68.0, 70.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 84.0, 85.0, 84.0, 80.0, 102.0, 108.0, 106.0], "value": 90.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 142.0, 136.0, 136.0, 135.0, 135.0, 135.0, 225.0, 140.0], "value": 148.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 169.0, 165.0, 168.0, 166.0, 163.0, 171.0, 230.0, 187.0], "value": 175.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 60.0, 66.0, 80.0, 83.0, 86.0, 80.0, 66.0, 65.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1508.5, "alertThreshold": 2.0}]} 15:23:50 INFO - Return code: 0 15:23:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:23:51 ERROR - # TBPL SUCCESS # 15:23:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:23:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:23:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:23:51 INFO - 2016-05-11 15:23:51.050 screenresolution[1675:10207] starting screenresolution argv=screenresolution get 15:23:51 INFO - 2016-05-11 15:23:51.053 screenresolution[1675:10207] Display 0: 1600x1200x32@60 15:23:51 INFO - 2016-05-11 15:23:51.059 screenresolution[1676:10210] starting screenresolution argv=screenresolution list 15:23:51 INFO - Available Modes on Display 0 15:23:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:23:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:23:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:23:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:23:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:23:51 INFO - Intel Iris: 15:23:51 INFO - Chipset Model: Intel Iris 15:23:51 INFO - Type: GPU 15:23:51 INFO - Bus: Built-In 15:23:51 INFO - VRAM (Dynamic, Max): 1536 MB 15:23:51 INFO - Vendor: Intel (0x8086) 15:23:51 INFO - Device ID: 0x0a2e 15:23:51 INFO - Revision ID: 0x0009 15:23:51 INFO - Displays: 15:23:51 INFO - Display: 15:23:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:23:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:23:51 INFO - Display Serial Number: 1600x1200 60 15:23:51 INFO - Main Display: Yes 15:23:51 INFO - Mirror: Off 15:23:51 INFO - Online: Yes 15:23:51 INFO - Rotation: Supported 15:23:51 INFO - Return code: 0 15:23:51 INFO - Running post-action listener: _resource_record_post_action 15:23:51 INFO - ##### 15:23:51 INFO - ##### Finished run-tests step (success) 15:23:51 INFO - ##### 15:23:51 INFO - Running post-run listener: _resource_record_post_run 15:23:52 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37881344; Write bytes: 695575040; Read time: 392; Write time: 2258 15:23:52 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 395100672; Write bytes: 396629504; Read time: 30140; Write time: 806 15:23:52 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 36091392; Write bytes: 298097664; Read time: 373; Write time: 1451 15:23:52 INFO - Running post-run listener: _upload_blobber_files 15:23:52 INFO - Blob upload gear active. 15:23:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:23:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 15:23:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:23:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:23:52 INFO - (blobuploader) - INFO - Open directory for files ... 15:23:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:23:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:23:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:53 INFO - (blobuploader) - INFO - Done attempting. 15:23:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:23:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:23:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:23:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:23:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:23:54 INFO - (blobuploader) - INFO - Done attempting. 15:23:54 INFO - (blobuploader) - INFO - Iteration through files over. 15:23:54 INFO - Return code: 0 15:23:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:23:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:23:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65a0f62fd3b496a3124643811f891874dc56ff5138f7297f5257dd5c0b40b62d463f5cef82288269d1c012fadd3413b8aa8e43d7e59c12698b2788a89207b792", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93bd02c1005f224b824a07bce235b3fd64c8c4f89ca4796776f3e5f71fa681d4090f6371cb1056055bf42699253f6d73ca7b04bcd81e9377123c33b09be1be50"} 15:23:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:23:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:23:54 INFO - Contents: 15:23:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65a0f62fd3b496a3124643811f891874dc56ff5138f7297f5257dd5c0b40b62d463f5cef82288269d1c012fadd3413b8aa8e43d7e59c12698b2788a89207b792", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93bd02c1005f224b824a07bce235b3fd64c8c4f89ca4796776f3e5f71fa681d4090f6371cb1056055bf42699253f6d73ca7b04bcd81e9377123c33b09be1be50"} 15:23:54 INFO - Running post-run listener: copy_logs_to_upload_dir 15:23:54 INFO - Copying logs to upload dir... 15:23:54 INFO - mkdir: /builds/slave/test/build/upload/logs 15:23:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.476047 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-11 15:23:54.961873) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 15:23:54.965464) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jjCDMRm0KF/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65a0f62fd3b496a3124643811f891874dc56ff5138f7297f5257dd5c0b40b62d463f5cef82288269d1c012fadd3413b8aa8e43d7e59c12698b2788a89207b792", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93bd02c1005f224b824a07bce235b3fd64c8c4f89ca4796776f3e5f71fa681d4090f6371cb1056055bf42699253f6d73ca7b04bcd81e9377123c33b09be1be50"} build_url:https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011348 build_url: 'https://queue.taskcluster.net/v1/task/PJvYRQvgQzuGo_bvHJgRgg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65a0f62fd3b496a3124643811f891874dc56ff5138f7297f5257dd5c0b40b62d463f5cef82288269d1c012fadd3413b8aa8e43d7e59c12698b2788a89207b792", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/93bd02c1005f224b824a07bce235b3fd64c8c4f89ca4796776f3e5f71fa681d4090f6371cb1056055bf42699253f6d73ca7b04bcd81e9377123c33b09be1be50"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 15:23:54.993126) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:23:54.993439) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jjCDMRm0KF/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.m6pvyPn6bj/Listeners TMPDIR=/var/folders/8g/gc76032d21n7d6r0hkpdqbjr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005405 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 15:23:55.041712) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 15:23:55.042002) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 15:23:55.042300) ========= ========= Total master_lag: 0.15 =========