builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0129 starttime: 1462995387.46 results: success (0) buildid: 20160511104611 builduid: 0adef02fcfc744b4bcf38f175d1fcb0f revision: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.461455) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.462439) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.463272) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.481765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.482405) ========= 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.wXszDkKqkj/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.cu6IdakZ2i/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:27-- 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% 39.8M=0s 2016-05-11 12:36:27 (39.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.322347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.826145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.826523) ========= 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.wXszDkKqkj/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.cu6IdakZ2i/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.020727 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:27.875093) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 12:36:27.875483) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 --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 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wXszDkKqkj/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.cu6IdakZ2i/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:27,949 truncating revision to first 12 chars 2016-05-11 12:36:27,949 Setting DEBUG logging. 2016-05-11 12:36:27,949 attempt 1/10 2016-05-11 12:36:27,949 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45daaf6edeae?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 12:36:29,339 unpacking tar archive at: fx-team-45daaf6edeae/testing/mozharness/ program finished with exit code 0 elapsedTime=1.628046 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 12:36:29.524028) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:29.524770) ========= script_repo_revision: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:29.525464) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:29.526158) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 12:36:29.539395) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-11 12:36:29.539809) ========= /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.wXszDkKqkj/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.cu6IdakZ2i/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:29 INFO - MultiFileLogger online at 20160511 12:36:29 in /builds/slave/test 12:36:29 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 12:36:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:36:29 INFO - {'append_to_log': False, 12:36:29 INFO - 'base_work_dir': '/builds/slave/test', 12:36:29 INFO - 'blob_upload_branch': 'Fx-Team', 12:36:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:36:29 INFO - 'branch': 'Fx-Team', 12:36:29 INFO - 'buildbot_json_path': 'buildprops.json', 12:36:29 INFO - 'config_files': ('talos/mac_config.py',), 12:36:29 INFO - 'default_actions': ('clobber', 12:36:29 INFO - 'read-buildbot-config', 12:36:29 INFO - 'download-and-extract', 12:36:29 INFO - 'populate-webroot', 12:36:29 INFO - 'create-virtualenv', 12:36:29 INFO - 'install', 12:36:29 INFO - 'run-tests'), 12:36:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:36:29 INFO - 'download_minidump_stackwalk': True, 12:36:29 INFO - 'download_symbols': 'ondemand', 12:36:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:36:29 INFO - 'tooltool.py': '/tools/tooltool.py', 12:36:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:36:29 INFO - '/tools/misc-python/virtualenv.py')}, 12:36:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:36:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:36:29 INFO - 'installer_path': 'installer.exe', 12:36:29 INFO - 'log_level': 'info', 12:36:29 INFO - 'log_name': 'talos', 12:36:29 INFO - 'log_to_console': True, 12:36:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:36:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:36:29 INFO - 'opt_config_files': (), 12:36:29 INFO - 'pip_index': False, 12:36:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:36:29 INFO - 'cmd': ('bash', 12:36:29 INFO - '-c', 12:36:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:36:29 INFO - 'enabled': True, 12:36:29 INFO - 'halt_on_failure': False, 12:36:29 INFO - 'name': 'check_screen_resolution'},), 12:36:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:36:29 INFO - 'cmd': ('bash', 12:36:29 INFO - '-c', 12:36:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:36:29 INFO - 'enabled': True, 12:36:29 INFO - 'halt_on_failure': False, 12:36:29 INFO - 'name': 'check_screen_resolution'},), 12:36:29 INFO - 'run_cmd_checks_enabled': True, 12:36:29 INFO - 'sps_profile': False, 12:36:29 INFO - 'sps_profile_interval': 0, 12:36:29 INFO - 'suite': 'dromaeojs', 12:36:29 INFO - 'system_bits': '32', 12:36:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:36:29 INFO - 'title': 't-yosemite-r7-0129', 12:36:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:36:29 INFO - 'use_talos_json': True, 12:36:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:36:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:36:29 INFO - 'work_dir': 'build'} 12:36:29 INFO - ##### 12:36:29 INFO - ##### Running clobber step. 12:36:29 INFO - ##### 12:36:29 INFO - Running pre-action listener: _resource_record_pre_action 12:36:29 INFO - Running main action method: clobber 12:36:29 INFO - rmtree: /builds/slave/test/build 12:36:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:36:30 INFO - Running post-action listener: _resource_record_post_action 12:36:30 INFO - ##### 12:36:30 INFO - ##### Finished clobber step (success) 12:36:30 INFO - ##### 12:36:30 INFO - ##### 12:36:30 INFO - ##### Running read-buildbot-config step. 12:36:30 INFO - ##### 12:36:30 INFO - Running pre-action listener: _resource_record_pre_action 12:36:30 INFO - Running main action method: read_buildbot_config 12:36:30 INFO - Using buildbot properties: 12:36:30 INFO - { 12:36:30 INFO - "project": "", 12:36:30 INFO - "product": "firefox", 12:36:30 INFO - "script_repo_revision": "production", 12:36:30 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:36:30 INFO - "repository": "", 12:36:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:36:30 INFO - "buildid": "20160511104611", 12:36:30 INFO - "pgo_build": "False", 12:36:30 INFO - "basedir": "/builds/slave/test", 12:36:30 INFO - "buildnumber": 12, 12:36:30 INFO - "slavename": "t-yosemite-r7-0129", 12:36:30 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:36:30 INFO - "platform": "yosemite_r7", 12:36:30 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:36:30 INFO - "branch": "fx-team", 12:36:30 INFO - "repo_path": "integration/fx-team", 12:36:30 INFO - "revision": "45daaf6edeae80ec8c67da50fa1d31f4a1b1a454", 12:36:30 INFO - "stage_platform": "macosx64", 12:36:30 INFO - "builduid": "0adef02fcfc744b4bcf38f175d1fcb0f", 12:36:30 INFO - "slavebuilddir": "test" 12:36:30 INFO - } 12:36:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:36:30 INFO - Running post-action listener: _resource_record_post_action 12:36:30 INFO - ##### 12:36:30 INFO - ##### Finished read-buildbot-config step (success) 12:36:30 INFO - ##### 12:36:30 INFO - ##### 12:36:30 INFO - ##### Running download-and-extract step. 12:36:30 INFO - ##### 12:36:30 INFO - Running pre-action listener: _resource_record_pre_action 12:36:30 INFO - Running main action method: download_and_extract 12:36:30 INFO - mkdir: /builds/slave/test/build/tests 12:36:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:30 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:36:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:36:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:36:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:32 INFO - Downloaded 1199 bytes. 12:36:32 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:36:32 INFO - Using the following test package requirements: 12:36:32 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:36:32 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:36:32 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:36:32 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'jsshell-mac.zip'], 12:36:32 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:36:32 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:36:32 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:36:32 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:36:32 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:36:32 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:36:32 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:36:32 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:36: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')]} 12:36:32 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:36:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:36:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:36:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:33 INFO - Downloaded 18150555 bytes. 12:36:33 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'] 12:36:33 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 12:36:36 INFO - Return code: 0 12:36:36 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 12:36:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:36 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:36:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:36:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:36:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:38 INFO - Downloaded 18150555 bytes. 12:36:38 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'] 12:36:38 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 12:36:42 INFO - Return code: 0 12:36:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:42 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:36:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:36:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:36:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:43 INFO - Downloaded 10840152 bytes. 12:36:43 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'] 12:36:43 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 12:36:43 INFO - Return code: 0 12:36:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:43 INFO - https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:36:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:36:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:36:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:36:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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 12:36:46 INFO - Downloaded 109797959 bytes. 12:36:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:36:46 INFO - mkdir: /builds/slave/test/properties 12:36:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:36:46 INFO - Writing to file /builds/slave/test/properties/build_url 12:36:46 INFO - Contents: 12:36:46 INFO - build_url:https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:36:48 INFO - Running post-action listener: _resource_record_post_action 12:36:48 INFO - Running post-action listener: set_extra_try_arguments 12:36:48 INFO - ##### 12:36:48 INFO - ##### Finished download-and-extract step (success) 12:36:48 INFO - ##### 12:36:48 INFO - ##### 12:36:48 INFO - ##### Running populate-webroot step. 12:36:48 INFO - ##### 12:36:48 INFO - Running pre-action listener: _resource_record_pre_action 12:36:48 INFO - Running main action method: populate_webroot 12:36:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:36:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:36:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:36:48 INFO - '--tppagecycles', 12:36:48 INFO - '7'], 12:36:48 INFO - 'tests': ['tsvgm']}}, 12:36:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:36:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:36:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:36:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:36:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:36:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:36:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['damp', 'tps']}, 12:36:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'tests': ['damp', 'tps']}, 12:36:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['dromaeo_dom']}, 12:36:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:36:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['a11yr', 12:36:48 INFO - 'ts_paint', 12:36:48 INFO - 'tpaint', 12:36:48 INFO - 'sessionrestore', 12:36:48 INFO - 'sessionrestore_no_auto_restore', 12:36:48 INFO - 'tabpaint']}, 12:36:48 INFO - 'other-e10s': {'tests': ['a11yr', 12:36:48 INFO - 'ts_paint', 12:36:48 INFO - 'tpaint', 12:36:48 INFO - 'sessionrestore', 12:36:48 INFO - 'sessionrestore_no_auto_restore', 12:36:48 INFO - 'tabpaint']}, 12:36:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:36:48 INFO - 'ts_paint', 12:36:48 INFO - 'tpaint', 12:36:48 INFO - 'sessionrestore', 12:36:48 INFO - 'sessionrestore_no_auto_restore', 12:36:48 INFO - 'tabpaint']}, 12:36:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:36:48 INFO - 'ts_paint', 12:36:48 INFO - 'tpaint', 12:36:48 INFO - 'sessionrestore', 12:36:48 INFO - 'sessionrestore_no_auto_restore', 12:36:48 INFO - 'tabpaint']}, 12:36:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['a11yr', 12:36:48 INFO - 'ts_paint', 12:36:48 INFO - 'tpaint', 12:36:48 INFO - 'sessionrestore', 12:36:48 INFO - 'sessionrestore_no_auto_restore', 12:36:48 INFO - 'tabpaint']}, 12:36:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['a11yr', 12:36:48 INFO - 'ts_paint', 12:36:48 INFO - 'tpaint', 12:36:48 INFO - 'sessionrestore', 12:36:48 INFO - 'sessionrestore_no_auto_restore', 12:36:48 INFO - 'tabpaint']}, 12:36:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['tsvgx', 12:36:48 INFO - 'tsvgr_opacity', 12:36:48 INFO - 'tart', 12:36:48 INFO - 'tscrollx', 12:36:48 INFO - 'cart']}, 12:36:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:36:48 INFO - 'tsvgr_opacity', 12:36:48 INFO - 'tart', 12:36:48 INFO - 'tscrollx', 12:36:48 INFO - 'cart']}, 12:36:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'talos_options': ['--disable-e10s'], 12:36:48 INFO - 'tests': ['tp5o']}, 12:36:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'tests': ['tp5o']}, 12:36:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'talos_options': ['--disable-e10s', 12:36:48 INFO - '--xperf_path', 12:36:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:36:48 INFO - 'tests': ['tp5n']}, 12:36:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:36:48 INFO - 'talos_options': ['--xperf_path', 12:36:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:36:48 INFO - 'tests': ['tp5n']}}, 12:36:48 INFO - 'talos.zip': {'path': '', 12:36:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:36:48 INFO - Running post-action listener: _resource_record_post_action 12:36:48 INFO - ##### 12:36:48 INFO - ##### Finished populate-webroot step (success) 12:36:48 INFO - ##### 12:36:48 INFO - ##### 12:36:48 INFO - ##### Running create-virtualenv step. 12:36:48 INFO - ##### 12:36:48 INFO - Running pre-action listener: _resource_record_pre_action 12:36:48 INFO - Running main action method: create_virtualenv 12:36:48 INFO - Creating virtualenv /builds/slave/test/build/venv 12:36:48 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 12:36:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:36:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:36:48 INFO - Using real prefix '/tools/python27' 12:36:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:36:49 INFO - Installing distribute.............................................................................................................................................................................................done. 12:36:52 INFO - Installing pip.................done. 12:36:52 INFO - Return code: 0 12:36:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:36:52 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')]} 12:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:52 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')]} 12:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:52 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 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:52 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 12:36:52 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 12:36:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:36:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:52 INFO - 'HOME': '/Users/cltbld', 12:36:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:52 INFO - 'LOGNAME': 'cltbld', 12:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:52 INFO - 'MOZ_NO_REMOTE': '1', 12:36:52 INFO - 'NO_EM_RESTART': '1', 12:36:52 INFO - 'PAGER': '/bin/cat', 12:36:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:52 INFO - 'PWD': '/builds/slave/test', 12:36:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:52 INFO - 'SHELL': '/bin/bash', 12:36:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:36:52 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:36:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:52 INFO - 'USER': 'cltbld', 12:36:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:52 INFO - 'XPC_FLAGS': '0x0', 12:36:52 INFO - 'XPC_SERVICE_NAME': '0', 12:36:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:52 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 12:36:52 INFO - Cleaning up... 12:36:52 INFO - Return code: 0 12:36:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:36:52 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')]} 12:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:52 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')]} 12:36:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:52 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 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:52 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 12:36:52 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 12:36:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:36:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:52 INFO - 'HOME': '/Users/cltbld', 12:36:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:52 INFO - 'LOGNAME': 'cltbld', 12:36:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:52 INFO - 'MOZ_NO_REMOTE': '1', 12:36:52 INFO - 'NO_EM_RESTART': '1', 12:36:52 INFO - 'PAGER': '/bin/cat', 12:36:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:52 INFO - 'PWD': '/builds/slave/test', 12:36:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:52 INFO - 'SHELL': '/bin/bash', 12:36:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:36:52 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:36:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:52 INFO - 'USER': 'cltbld', 12:36:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:52 INFO - 'XPC_FLAGS': '0x0', 12:36:52 INFO - 'XPC_SERVICE_NAME': '0', 12:36:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:52 INFO - Downloading/unpacking psutil>=0.7.1 12:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:36:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:36:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:36:55 INFO - Installing collected packages: psutil 12:36:55 INFO - Running setup.py install for psutil 12:36:55 INFO - building 'psutil._psutil_osx' extension 12:36:55 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 12:36:55 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 12:36:56 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 12:36:56 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 12:36:56 INFO - building 'psutil._psutil_posix' extension 12:36:56 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 12:36:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:36:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:36:56 INFO - ^ 12:36:56 INFO - 1 warning generated. 12:36:56 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 12:36:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:36:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:36:56 INFO - Successfully installed psutil 12:36:56 INFO - Cleaning up... 12:36:56 INFO - Return code: 0 12:36:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:36: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')]} 12:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36: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')]} 12:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:56 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 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:56 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 12:36:56 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 12:36:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:36:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:56 INFO - 'HOME': '/Users/cltbld', 12:36:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:56 INFO - 'LOGNAME': 'cltbld', 12:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:56 INFO - 'MOZ_NO_REMOTE': '1', 12:36:56 INFO - 'NO_EM_RESTART': '1', 12:36:56 INFO - 'PAGER': '/bin/cat', 12:36:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:56 INFO - 'PWD': '/builds/slave/test', 12:36:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:56 INFO - 'SHELL': '/bin/bash', 12:36:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:36:56 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:36:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:56 INFO - 'USER': 'cltbld', 12:36:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:56 INFO - 'XPC_FLAGS': '0x0', 12:36:56 INFO - 'XPC_SERVICE_NAME': '0', 12:36:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:36:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:36:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:36:59 INFO - Installing collected packages: mozsystemmonitor 12:36:59 INFO - Running setup.py install for mozsystemmonitor 12:36:59 INFO - Successfully installed mozsystemmonitor 12:36:59 INFO - Cleaning up... 12:36:59 INFO - Return code: 0 12:36:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:36:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:36:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:36:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:36:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:36:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:36:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:36:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:36:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:36:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:36:59 INFO - 'HOME': '/Users/cltbld', 12:36:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:36:59 INFO - 'LOGNAME': 'cltbld', 12:36:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:36:59 INFO - 'MOZ_NO_REMOTE': '1', 12:36:59 INFO - 'NO_EM_RESTART': '1', 12:36:59 INFO - 'PAGER': '/bin/cat', 12:36:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:36:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:36:59 INFO - 'PWD': '/builds/slave/test', 12:36:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:36:59 INFO - 'SHELL': '/bin/bash', 12:36:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:36:59 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:36:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:36:59 INFO - 'USER': 'cltbld', 12:36:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:36:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:36:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:36:59 INFO - 'XPC_FLAGS': '0x0', 12:36:59 INFO - 'XPC_SERVICE_NAME': '0', 12:36:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:36:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:36:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:36:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:36:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:36:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:02 INFO - Downloading blobuploader-1.2.4.tar.gz 12:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:37:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:37:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:03 INFO - Downloading docopt-0.6.1.tar.gz 12:37:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:37:03 INFO - Installing collected packages: blobuploader, requests, docopt 12:37:03 INFO - Running setup.py install for blobuploader 12:37:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:37:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:37:03 INFO - Running setup.py install for requests 12:37:04 INFO - Running setup.py install for docopt 12:37:04 INFO - Successfully installed blobuploader requests docopt 12:37:04 INFO - Cleaning up... 12:37:04 INFO - Return code: 0 12:37:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:04 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 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:37:04 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 12:37:04 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 12:37:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:37:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:04 INFO - 'HOME': '/Users/cltbld', 12:37:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:04 INFO - 'LOGNAME': 'cltbld', 12:37:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:04 INFO - 'MOZ_NO_REMOTE': '1', 12:37:04 INFO - 'NO_EM_RESTART': '1', 12:37:04 INFO - 'PAGER': '/bin/cat', 12:37:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:04 INFO - 'PWD': '/builds/slave/test', 12:37:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:04 INFO - 'SHELL': '/bin/bash', 12:37:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:37:04 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:37:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:04 INFO - 'USER': 'cltbld', 12:37:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:04 INFO - 'XPC_FLAGS': '0x0', 12:37:04 INFO - 'XPC_SERVICE_NAME': '0', 12:37:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:37:04 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-33XCu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:37:04 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-0tHwle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:37:04 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ZGCdlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:37:04 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-B_WiDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:37:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:37:04 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-BjJlxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-V47u8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-paMJVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-_Tg7xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-zUzjj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-zNZJ1v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-56IQA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-8SyzxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-lQ74KU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-lw7VH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:37:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:37:05 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-hftZuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:37:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-V6tVd3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:37:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:37:06 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-fycfrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:37:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:37:06 INFO - Running setup.py install for manifestparser 12:37:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:37:06 INFO - Running setup.py install for mozcrash 12:37:06 INFO - Running setup.py install for mozdebug 12:37:06 INFO - Running setup.py install for mozdevice 12:37:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:37:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:37:06 INFO - Running setup.py install for mozfile 12:37:07 INFO - Running setup.py install for mozhttpd 12:37:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Running setup.py install for mozinfo 12:37:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Running setup.py install for mozInstall 12:37:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Running setup.py install for mozleak 12:37:07 INFO - Running setup.py install for mozlog 12:37:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Running setup.py install for moznetwork 12:37:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:37:07 INFO - Running setup.py install for mozprocess 12:37:08 INFO - Running setup.py install for mozprofile 12:37:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:37:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:37:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:37:08 INFO - Running setup.py install for mozrunner 12:37:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:37:08 INFO - Running setup.py install for mozscreenshot 12:37:08 INFO - Running setup.py install for moztest 12:37:08 INFO - Running setup.py install for mozversion 12:37:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:37:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:37:08 INFO - Cleaning up... 12:37:08 INFO - Return code: 0 12:37:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:37:08 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')]} 12:37:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:08 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')]} 12:37:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:08 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 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:37:08 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 12:37:08 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 12:37:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:37:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:08 INFO - 'HOME': '/Users/cltbld', 12:37:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:08 INFO - 'LOGNAME': 'cltbld', 12:37:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:08 INFO - 'MOZ_NO_REMOTE': '1', 12:37:08 INFO - 'NO_EM_RESTART': '1', 12:37:08 INFO - 'PAGER': '/bin/cat', 12:37:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:08 INFO - 'PWD': '/builds/slave/test', 12:37:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:08 INFO - 'SHELL': '/bin/bash', 12:37:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:37:08 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:37:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:08 INFO - 'USER': 'cltbld', 12:37:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:08 INFO - 'XPC_FLAGS': '0x0', 12:37:08 INFO - 'XPC_SERVICE_NAME': '0', 12:37:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-pdMDd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-2ZDD5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-TicwY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-q7C_O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-i16rnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-XNe_1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-5JX5Kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-5Vjde2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:37:09 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)) 12:37:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:37:09 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-0d2CVL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-1x9d85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-uaYTqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-EDpj_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-KWyD5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-PZUDMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-JMuiDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-ZPMK0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:37:10 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)) 12:37:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:37:10 INFO - Running setup.py (path:/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/pip-TNi1uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:37:10 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)) 12:37:10 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)) 12:37:10 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)) 12:37:10 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)) 12:37:10 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)) 12:37:10 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)) 12:37:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:37: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 12:37: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 12:37: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 12:37: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 12:37: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 12:37: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 12:37:13 INFO - Downloading blessings-1.6.tar.gz 12:37:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:37:13 INFO - Installing collected packages: blessings 12:37:13 INFO - Running setup.py install for blessings 12:37:13 INFO - Successfully installed blessings 12:37:13 INFO - Cleaning up... 12:37:13 INFO - Return code: 0 12:37:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:37:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:37:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:37:13 INFO - Reading from file tmpfile_stdout 12:37:13 INFO - Current package versions: 12:37:13 INFO - blessings == 1.6 12:37:13 INFO - blobuploader == 1.2.4 12:37:13 INFO - docopt == 0.6.1 12:37:13 INFO - manifestparser == 1.1 12:37:13 INFO - mozInstall == 1.12 12:37:13 INFO - mozcrash == 0.17 12:37:13 INFO - mozdebug == 0.1 12:37:13 INFO - mozdevice == 0.48 12:37:13 INFO - mozfile == 1.2 12:37:13 INFO - mozhttpd == 0.7 12:37:13 INFO - mozinfo == 0.9 12:37:13 INFO - mozleak == 0.1 12:37:13 INFO - mozlog == 3.1 12:37:13 INFO - moznetwork == 0.27 12:37:13 INFO - mozprocess == 0.22 12:37:13 INFO - mozprofile == 0.28 12:37:13 INFO - mozrunner == 6.11 12:37:13 INFO - mozscreenshot == 0.1 12:37:13 INFO - mozsystemmonitor == 0.0 12:37:13 INFO - moztest == 0.7 12:37:13 INFO - mozversion == 1.4 12:37:13 INFO - psutil == 3.1.1 12:37:13 INFO - requests == 1.2.3 12:37:13 INFO - wsgiref == 0.1.2 12:37:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:37: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')]} 12:37:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37: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')]} 12:37:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:13 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 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:37:13 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 12:37:13 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 12:37:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:37:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:13 INFO - 'HOME': '/Users/cltbld', 12:37:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:13 INFO - 'LOGNAME': 'cltbld', 12:37:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:13 INFO - 'MOZ_NO_REMOTE': '1', 12:37:13 INFO - 'NO_EM_RESTART': '1', 12:37:13 INFO - 'PAGER': '/bin/cat', 12:37:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:13 INFO - 'PWD': '/builds/slave/test', 12:37:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:13 INFO - 'SHELL': '/bin/bash', 12:37:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:37:13 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:37:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:13 INFO - 'USER': 'cltbld', 12:37:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:13 INFO - 'XPC_FLAGS': '0x0', 12:37:13 INFO - 'XPC_SERVICE_NAME': '0', 12:37:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 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)) 12:37:14 INFO - Cleaning up... 12:37:14 INFO - Return code: 0 12:37:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:37:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:37:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:37:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:37:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:14 INFO - 'HOME': '/Users/cltbld', 12:37:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:14 INFO - 'LOGNAME': 'cltbld', 12:37:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:14 INFO - 'MOZ_NO_REMOTE': '1', 12:37:14 INFO - 'NO_EM_RESTART': '1', 12:37:14 INFO - 'PAGER': '/bin/cat', 12:37:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:14 INFO - 'PWD': '/builds/slave/test', 12:37:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:14 INFO - 'SHELL': '/bin/bash', 12:37:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:37:14 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:37:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:14 INFO - 'USER': 'cltbld', 12:37:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:14 INFO - 'XPC_FLAGS': '0x0', 12:37:14 INFO - 'XPC_SERVICE_NAME': '0', 12:37:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:14 INFO - Downloading/unpacking jsonschema 12:37:14 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 12:37:14 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 12:37:14 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 12:37:14 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 12:37:14 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 12:37:14 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 12:37:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:37:16 INFO - Downloading/unpacking functools32 (from jsonschema) 12:37: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 12:37: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 12:37: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 12:37: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 12:37: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 12:37: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 12:37:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:37:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:37:17 INFO - warning: no files found matching '*.txt' 12:37:17 INFO - no previously-included directories found matching 'build' 12:37:17 INFO - no previously-included directories found matching 'dist' 12:37:17 INFO - no previously-included directories found matching '.git*' 12:37:17 INFO - Installing collected packages: jsonschema, functools32 12:37:17 INFO - Running setup.py install for functools32 12:37:17 INFO - warning: no files found matching '*.txt' 12:37:17 INFO - no previously-included directories found matching 'build' 12:37:17 INFO - no previously-included directories found matching 'dist' 12:37:17 INFO - no previously-included directories found matching '.git*' 12:37:17 INFO - Successfully installed jsonschema functools32 12:37:17 INFO - Cleaning up... 12:37:17 INFO - Return code: 0 12:37:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:37:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:37:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c833a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c839570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f875a44c540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, '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.wXszDkKqkj/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.cu6IdakZ2i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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 12:37:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:37:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:37:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:37:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:17 INFO - 'HOME': '/Users/cltbld', 12:37:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:17 INFO - 'LOGNAME': 'cltbld', 12:37:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:17 INFO - 'MOZ_NO_REMOTE': '1', 12:37:17 INFO - 'NO_EM_RESTART': '1', 12:37:17 INFO - 'PAGER': '/bin/cat', 12:37:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:17 INFO - 'PWD': '/builds/slave/test', 12:37:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:17 INFO - 'SHELL': '/bin/bash', 12:37:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:37:17 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:37:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:17 INFO - 'USER': 'cltbld', 12:37:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:17 INFO - 'XPC_FLAGS': '0x0', 12:37:17 INFO - 'XPC_SERVICE_NAME': '0', 12:37:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:18 INFO - Downloading/unpacking flake8 12:37:18 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 12:37:18 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 12:37:18 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 12:37:18 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 12:37:18 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 12:37:18 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 12:37:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:37:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:37:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:37:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:37:21 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 12:37:21 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 12:37:21 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 12:37:21 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 12:37:21 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 12:37:21 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 12:37:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:37:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:37:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:37:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:37:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:37:21 INFO - no previously-included directories found matching 'docs/_build' 12:37:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:37:21 INFO - Running setup.py install for pep8 12:37:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:37:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:37:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:37:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:37:21 INFO - no previously-included directories found matching 'docs/_build' 12:37:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:37:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:37:21 INFO - Cleaning up... 12:37:22 INFO - Return code: 0 12:37:22 INFO - Running post-action listener: _resource_record_post_action 12:37:22 INFO - Running post-action listener: _start_resource_monitoring 12:37:22 INFO - Starting resource monitoring. 12:37:22 INFO - ##### 12:37:22 INFO - ##### Finished create-virtualenv step (success) 12:37:22 INFO - ##### 12:37:22 INFO - ##### 12:37:22 INFO - ##### Running install step. 12:37:22 INFO - ##### 12:37:22 INFO - Running pre-action listener: _resource_record_pre_action 12:37:22 INFO - Running main action method: install 12:37:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:37:22 INFO - Reading from file tmpfile_stdout 12:37:22 INFO - Detecting whether we're running mozinstall >=1.0... 12:37:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:37:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:37:22 INFO - Reading from file tmpfile_stdout 12:37:22 INFO - Output received: 12:37:22 INFO - Usage: mozinstall [options] installer 12:37:22 INFO - Options: 12:37:22 INFO - -h, --help show this help message and exit 12:37:22 INFO - -d DEST, --destination=DEST 12:37:22 INFO - Directory to install application into. [default: 12:37:22 INFO - "/builds/slave/test"] 12:37:22 INFO - --app=APP Application being installed. [default: firefox] 12:37:22 INFO - mkdir: /builds/slave/test/build/application 12:37:22 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'] 12:37:22 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 12:37:56 INFO - Reading from file tmpfile_stdout 12:37:56 INFO - Output received: 12:37:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:37:56 INFO - Running post-action listener: _resource_record_post_action 12:37:56 INFO - ##### 12:37:56 INFO - ##### Finished install step (success) 12:37:56 INFO - ##### 12:37:56 INFO - ##### 12:37:56 INFO - ##### Running run-tests step. 12:37:56 INFO - ##### 12:37:56 INFO - Running pre-action listener: _resource_record_pre_action 12:37:56 INFO - Running main action method: run_tests 12:37:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:37:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:37:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:37:56 INFO - 2016-05-11 12:37:56.041 screenresolution[1935:11575] starting screenresolution argv=screenresolution get 12:37:56 INFO - 2016-05-11 12:37:56.044 screenresolution[1935:11575] Display 0: 1600x1200x32@60 12:37:56 INFO - 2016-05-11 12:37:56.050 screenresolution[1936:11578] starting screenresolution argv=screenresolution list 12:37:56 INFO - Available Modes on Display 0 12:37:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:37:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:37:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:37:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:37:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:37:56 INFO - Intel Iris: 12:37:56 INFO - Chipset Model: Intel Iris 12:37:56 INFO - Type: GPU 12:37:56 INFO - Bus: Built-In 12:37:56 INFO - VRAM (Dynamic, Max): 1536 MB 12:37:56 INFO - Vendor: Intel (0x8086) 12:37:56 INFO - Device ID: 0x0a2e 12:37:56 INFO - Revision ID: 0x0009 12:37:56 INFO - Displays: 12:37:56 INFO - Display: 12:37:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:37:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:37:56 INFO - Display Serial Number: 1600x1200 60 12:37:56 INFO - Main Display: Yes 12:37:56 INFO - Mirror: Off 12:37:56 INFO - Online: Yes 12:37:56 INFO - Rotation: Supported 12:37:56 INFO - Return code: 0 12:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:37:56 INFO - Python 2.7.3 12:37:56 INFO - Return code: 0 12:37:56 INFO - grabbing minidump binary from tooltool 12:37: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')]} 12:37:56 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 0x10c6d7d30>, '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 0x10c4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c6ceb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:37:56 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 12:37:56 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 12:37:56 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 12:37:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:37:56 INFO - Return code: 0 12:37:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:37:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:37:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:37:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:37:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:37:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:37:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:37:57 INFO - Return code: 0 12:37:57 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/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 12:37:57 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/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0129 --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 12:37:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wXszDkKqkj/Render', 12:37:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:57 INFO - 'HOME': '/Users/cltbld', 12:37:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:57 INFO - 'LOGNAME': 'cltbld', 12:37:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:37:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:57 INFO - 'MOZ_NO_REMOTE': '1', 12:37:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:37:57 INFO - 'NO_EM_RESTART': '1', 12:37:57 INFO - 'PAGER': '/bin/cat', 12:37:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:57 INFO - 'PWD': '/builds/slave/test', 12:37:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:37:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:57 INFO - 'SHELL': '/bin/bash', 12:37:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cu6IdakZ2i/Listeners', 12:37:57 INFO - 'TMPDIR': '/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/', 12:37:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:57 INFO - 'USER': 'cltbld', 12:37:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:57 INFO - 'XPC_FLAGS': '0x0', 12:37:57 INFO - 'XPC_SERVICE_NAME': '0', 12:37:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:57 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/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0129', '--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 12:37:57 INFO - mozversion application_buildid: 20160511104611 12:37:57 INFO - mozversion application_changeset: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 12:37:57 INFO - mozversion application_display_name: Nightly 12:37:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:37:57 INFO - mozversion application_name: Firefox 12:37:57 INFO - mozversion application_remotingname: firefox 12:37:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:37:57 INFO - mozversion application_vendor: Mozilla 12:37:57 INFO - mozversion application_version: 49.0a1 12:37:57 INFO - mozversion platform_buildid: 20160511104611 12:37:57 INFO - mozversion platform_changeset: 45daaf6edeae80ec8c67da50fa1d31f4a1b1a454 12:37:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:37:57 INFO - mozversion platform_version: 49.0a1 12:37:57 INFO - using testdate: 1462995477 12:37:57 INFO - actual date: 1462995477 12:37:57 INFO - starting webserver on 'localhost:49675' 12:37:57 INFO - SUITE-START | Running 2 tests 12:37:57 INFO - TEST-START | dromaeo_css 12:37:57 INFO - operating with platform_type : mac_ 12:37:57 INFO - Initialising browser for dromaeo_css test... 12:37:57 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpkqV_51/profile http://localhost:49675/getInfo.html) 12:37:59 INFO - PROCESS | 1954 | __metrics Screen width/height:1600/1200 12:37:59 INFO - PROCESS | 1954 | colorDepth:24 12:37:59 INFO - PROCESS | 1954 | Browser inner width/height: 1024/689 12:37:59 INFO - PROCESS | 1954 | __metrics 12:38:00 INFO - PROCESS | 1954 | 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" 12:38:00 ERROR - PROCESS | 1954 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:38:00 INFO - TEST-INFO | 1954: exit 0 12:38:00 INFO - Browser initialized. 12:38:00 INFO - Running cycle 1/1 for dromaeo_css test... 12:38:00 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmpkqV_51/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:12 INFO - PROCESS | 1956 | RSS: Main: 238813184 12:38:12 INFO - PROCESS | 1956 | 12:39:03 INFO - PROCESS | 1956 | 1462995543487 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] 12:41:15 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49675/tests/dromaeo/cssquery-ext.html) 12:41:16 INFO - PROCESS | 1956 | RSS: Main: 334790656 12:41:16 INFO - PROCESS | 1956 | 12:44:19 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-ext.html (next: http://localhost:49675/tests/dromaeo/cssquery-jquery.html) 12:44:19 INFO - PROCESS | 1956 | RSS: Main: 367005696 12:44:19 INFO - PROCESS | 1956 | 12:47:22 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49675/tests/dromaeo/cssquery-mootools.html) 12:47:22 INFO - PROCESS | 1956 | RSS: Main: 366567424 12:47:22 INFO - PROCESS | 1956 | 12:50:25 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49675/tests/dromaeo/cssquery-prototype.html) 12:50:25 INFO - PROCESS | 1956 | RSS: Main: 369491968 12:50:25 INFO - PROCESS | 1956 | 12:53:29 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49675/tests/dromaeo/cssquery-yui.html) 12:53:29 INFO - PROCESS | 1956 | RSS: Main: 337035264 12:53:29 INFO - PROCESS | 1956 | 12:56:32 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:49675/tests/dromaeo/cssquery-yui.html (next: http://localhost:49675/tests/dromaeo/cssquery-dojo.html) 12:56:33 INFO - PROCESS | 1956 | RSS: Main: 375046144 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | __start_tp_report 12:56:33 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 12:56:33 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 12:56:33 INFO - PROCESS | 1956 | |i|pagename|runs| 12:56:33 INFO - PROCESS | 1956 | |0;dojo.html;3452.5856897172325;3454.3091381723652;3461.982690086549;3473.218525831687;3526.241858000531;5671.7731290748325;5702.942970570293;5734.197212390269;5789.4500022497905;5824.708764561776;4860.319555262257;5737.340205876326;5813.6802475863815;5852.326982397026;5904.0553511438175;5152.304438900756;5162.096633089194;5220.765065572039;5222.112240919034;5312.335958005249;5304.814331498398;5314.990151871132;5339.759710813023;5355.839324820262;5391.595630327722;5150.690958542499;5177.094008548489;5183.766730497136;5257.842264732064;5264.947350526503;5223.608229382811;5244;5250.264962905197;5251.133562962106;5252.133397989327;5128.230765385185;5168.974155129238;5185.559227465632;5199.896002079937;5218;5625.887482250332;5698.401667824902;5701.486866182064;5708.001099807559;5710.343310519302;6230.937690623081;6235.469985051276;6284;6290.905636415458;6298.937010629881;6216.067589861512;6340.841478963062;6349.714262858182;6360.936390636127;6366.554341196118;5763.596548241583;5845.766169353178;5896.174535565025;5896.38088000758;5949.286085669704;6254.405831446005;6282.057691346243;6295.874082518416;6368.872622547523;6378;6257.530685198628;6270.404311590483;6271;6273.37266273369;6295.905561416583;6221.253449585988;6369.745210191633;6379.776707815204;6380.266269378988;6390.520710946697;6183.5671502994355;6258.217722784652;6272.247330320391;6338.524610654219;6348.428641422294;6282.528810339243;6317.652529110851;6331.68341582919;6335.524835637346;6351.96824015877;4877.3903262092235;4926.876828079255;4936.728479933567;5024.472430394756;5073.518015788494;4507.121111383314;4520.18636645407;4553.635709143261;4555.908881822345;4562.041971186021;5761.337446193657;5791.391903850119;5803.82588522345;5824.213731146241;5872.882542349214;6338.271098823602;6376.39424254695;6385.936140638627;6394.1687580614225;6398.584092034003;5758.280214973129;5775.306963164448;5813.82558523245;5821.592488525847;5832.154337621106;5309.814156504503;5423.132298832168;5453.727313634382;5462.20797984298;5481;6372.203474565679;6384.744610215632;6392.296847346881;6402.455791257796;6406.775762848185;6293.087502312231;6318.494520438446;6329.49364050884;6332.271788744353;6352.682365881595;5386.407495175449;6237.282712488122;6237.812865614039;6275.96862015687;6362.872742545216;6261.342559031418;6316.336784637547;6325.367463253822;6326.082718005955;6331.683415829098;6320.6839658016;6339.524535659936;6341.460975817107;6345.587536810092;6350;6005.36943620931;6099.612447337931;6148.354422785722;6165.753369865155;6259.96870015647;5181.689098654093;6045.0327947528185;6169.537284703575;6218.720157592828;6233.937660623335;5833.212516310244;6031.36670649593;6055.122007308827;6067.180930574316;6103.908441373472;6110.1445797587485;6140.105498721277;6142.617910969996;6221.09794079865;6233.750649974132;6060.212172417558;6090.291944953066;6092.573519853568;6107.358727333743;6140.109684095871 12:56:33 INFO - PROCESS | 1956 | |1;ext.html;491.6435584201452;521.2780299285489;523.4922125538228;526.2185654303361;556.5436342199393;41712.37431438527;43189.92025199372;43268.4856030039;43411;45528;43402;43676.12647747043;43802.561974380245;43945.7802710986;44034.77982610082;8522.914770852274;8719;9662.178714809268;9846.458444785534;9881.654142105028;7823.960880195619;7957.641906114239;8097.919020809775;8179.754607361789;8182.263596276334;7993.920060799404;8001.439899207058;8022.879656805153;8023.638936247883;8024.9197508025045;8614.396992210548;11472.885271147306;11594.246373985665;11790.646280611509;11824.93575578202;10499.475026248658;11352.659420217407;11683.123765717604;11749.706257343549;11751.353675547842;40507.7974610128;40892.79553602243;40936;40938.795306023574;40965.385519217234;3192.4732419150814;3387.762856600039;3398.640543782507;3414.382815131872;3749.585824567657;27756.861215693792;29070;29860.10419687413;30263.69736302631;30496;2652.5755879059257;2745;2748.725127487235;2759.365345970438;2795.53873610856;481571;484314;484407;484851;484905.99999999645;35493;36345.636543634755;61148;61257.69371153205;62034.68982655058;37707.434388484195;37723;37757.433638495444;37777.43333849994;37859;15246.390144394321;15397.384104635914;15412.306446209948;15419.151946643042;15422.922885385726;11676.591319303783;11858.169928105122;11875.584354547549;12075.215111017756;12086.335251561248;7993.640286187136;8195.754127376189;8197.262246397853;8257.42198046143;8301.460405073773;2813.310738868949;3191.888283910052;3199.168216263788;3239.2549713566013;3271.214908422009;956.4213650741201;1045.29442626227;1071.373246650718;1071.6838532632892;1099.2074554940452;28742.568861467094;29431.76250612431;29708.851455742584;29927.401451971273;30760.230994224876;1107.346665467362;1113.1984970821159;1129.4465711801158;1147.7302833834042;1206.9155159138952;15389.615259618375;15493.225338733018;15624.265753493093;15657.608559786097;15876.682466350609;657589;660872;662564;662670;664086;60671.69664151651;60767.39232607706;61390;61440.38559614347;61500.077498837556;1339.0961101256612;1373.7115205806892;1480.8445113263167;1497.3561368611602;1514.5683480208088;27996.44007119887;30486.847565762917;31334.373312534077;31877.521837172924;32744.508832367057;55916;56311.71844140753;56535.71732141313;56621.86756264934;56668.71665641645;570625;576121;577859;578520;578908;26765.86617066902;27028.729712703407;27836;27859;28860.855695721388;45847.47276299307;46536.301955471376;46899.765501172275;47509;48144;36815.63184368122;37849.62150378461;38137;38493;38869.41695874621;37504.43743343907;37669.62330376661;37828.621713783614;38612.034699131626;38800.80599596984;37362.626373737;38270;38304;39064.6093539061;41007.58992410157;26779.33051673724;30969.380612387176;32348.514772277966;33594.32811343808;34105.31789364149;24702;24906.377340566632;24919.50482970955;25180.24459266225;25337 12:56:33 INFO - PROCESS | 1956 | |2;jquery.html;337.29841928788125;351.40787772603164;390.8866428735667;410.15508053409957;419.2139737991266;18933.526661833443;20924.849133297663;20976.370708878738;21013.36959891202;21026.89486552565;18522.907385463055;19050;20469.385918422435;21186.25848095317;21461.356159315208;5042.243663450475;5873.794417195389;6114.541409394291;6128.417800308964;6132.018876979684;5275.419703832576;6062.393760623946;6073.544484163684;6099.7865074722395;6109.022556390978;5869.060950247961;5946.197263369433;6004.399560044004;6005.87988240235;6044.304904935922;5926.496247818941;5949.494292985101;6029.366916473773;6043.153958445821;6052.485538729214;5139.460356662534;5253.658512196733;5256.448072952361;5260.368755749297;5261.289725886994;25578.97684092634;25820.483590328276;26055.478890422273;26104.73895261061;26426.07508737185;2269.8297627677825;2317.4090606895134;2362.4684445999683;2384.284714585635;2822.8024038317326;7026.683799229047;7051.400630946325;10166.440845753481;11598.304101753838;15361.846381536154;1731.8268173182707;1737.148797089417;1741.1990484377202;1783.8840475369188;1803.3417802502124;116802;117897.41051294774;118993.99999999914;119513.20730189054;121935;10423.5830566778;10473.423961682181;10624.840627390595;10638.840417393745;10711.517981690842;13235.867641323655;13300.800487992688;13353.599392018255;13354.799678004838;13359.666008349868;8439.240468357877;8645;8669.956650216709;8773.166549177946;8780.736577902673;8651.95674021626;8671.6531338747;8676.956615216883;8795.912040879637;8824.205821476098;6493.740250390026;6612.5040621952585;6638.302978187317;6650.235222949326;6665.233498147678;1698.1678977301033;1830.5606654402848;1849.4729002234299;1884.7738271407245;1919.4913347962802;829.4401279136558;941.7975135345933;957.4925289596385;974.45917515778;1083.7724077943562;20342;21135;21326.893365533073;21469.892650536647;21617.135314587555;964.9131578157859;969.9272554558436;975.7804493988796;978.3738407419257;1051.3113910388709;9344.719658410258;11295.604653837076;11307.483505379347;11387.829182562102;11397.316161030365;581622;582613;590628.5154316468;594720;609987;118146.22780658297;118275.40862295634;121042;121133.39433302777;122093.99999999822;1033.9793204135874;1058.513083981362;1060.2631171336002;1062.245805478119;1086.044281032702;12903.806442903177;17816.91091544534;18642;19144.712829307293;19185.712214317075;47180.7640961793;49070.509294906595;49157.77105572389;50385.992280154926;50510.74744626253;66460.66769666121;67733.50130729745;68031;68279.65860170667;68520.6573967127;7943.24539168778;8015.519068855887;8075.5558444286125;8141.755747327589;8165.836683266421;12073.456694448596;12325.93837030845;12482.937585312015;12634.431450584561;12899.742005159656;9956.701298961043;10307.381557106597;10673.092787113183;10836;11191.104711622887;10678.519466624177;10782.245242832927;10827.025567698629;10965.451727413756;11072.557097716;9705.951470242604;11155;11198.328100313682;11523.481443335239;11608.24546404481;6794.694238759169;7289.343959043499;7855.725049623465;7968.561729104723;8207.71273005442;10159.492025398848;10973.079711050694;11225.71935701614;11309.71725706864;11381.829272561085 12:56:33 INFO - PROCESS | 1956 | |3;mootools.html;1430.1490613085211;1608.3807734022403;1645.8847880648357;1891.1017266798272;2009.3469622372722;7863.252990965856;8526.275266602339;8759.080296568864;8779;8889.777755556115;8486.024107227671;8675.69635062773;8679.349048821348;8691.30469562435;8695.347848911326;2127.117246342766;2359.0210062823908;2419.9395015124633;2449.155041510685;2466.2847774145475;2459.606462965926;2477.838940468873;2485.875706214691;2486.9129580464687;2487.340854673513;1987.5826076523874;2195.242641288753;2449.9142530011454;2450.9387265318333;2455.226603619863;2107.399391173516;2349.4361353275262;2395.101836811196;2431.1004928176512;2431.319230615413;2428.162284012013;2447.8041756659422;2447.9877600612062;2453.4479742058074;2453.6196889482158;8762.430442021312;8818.470891746452;8824.161704638049;8840.778980525474;8862.335324850663;5105.795768169269;5420.430854760272;5423.3491980962535;5428.37573679028;5436.483534064258;3423.3495635829113;3499.632538583463;3579.4093974494176;3580.874669386585;3589.4795254688183;1009.7677534167183;1049.8740151181908;1055.767731099157;1055.8680164979378;1062.0866055192528;12146.210496317799;12170.695732606668;12172.57395991136;12184.573539926148;12202.145849790519;4822.517748225219;4861.6596838220985;4962.478939711278;4965.155923493015;4967.602591792648;10246;10270.589176432859;10279.383237005804;10289.022542858416;10305;5457.536109430664;5493.313335833021;5499.752511137009;5501.724913754297;5512.476314750093;4247.511536173318;4413.271810151289;4590.770461476913;4596.954030459653;4623.052274283831;3357.3788849063003;4072.450219220427;4140.420341152241;4143.606357396043;4152.522459917148;1237.5915947737226;1286.414681319997;1311.6458556189966;1349.4871948659643;1365.215001374214;793.1077537770009;855.422589751915;915.7619019055094;918.4581097152578;939.2908354192541;4749.786259618326;5884.235049443545;6006.399360063959;6031.487323577545;6150.723217455135;455.9749213793207;462.9467611224752;470.2945581627559;472.6006524486803;477.67279413601796;3904.72666913319;3982.38273067675;4050.4936882889638;4060.451839001697;4063.532693740258;10567.471626418648;10949.343039417661;10965.56137754497;10974.231803773819;10977.890221097687;10217.540210690537;10925.562977480971;10937.726556835984;10957.726056848483;10972.615958441416;8143.592820358958;8277.420580559303;8324.417290789708;8330.125336839545;8416.621252043673;5021.49785021495;5862.736176871966;5907.881842363215;5971.253593300838;5979.1031345296815;9694.17599504022;9999.900001000187;10015.899841001788;10117.898821011695;10171.898281017095;9154.816903661756;9973.401595904268;10083.193344532274;10425.9478702606;10445.582176713151;5583.720813959367;6728.596284222962;7126;7209.891851622125;7550.169481356937;7834.3340816028995;8517.233448989373;8539.573021348784;8686.609102590524;8687.782805429915;6683.933160668331;7536.698532058876;7624.351930086005;7689.192634773268;7840.411969102454;6488.870222595428;7446.776596701891;7472.364848987897;7630.16068232505;7630.618469076635;6840.555363901331;7094.53885497441;7104.786856394317;7105.147382314155;7118.537295075804;4750.881227969328;4845.515448455127;4899.4365647950945;4984.127777638972;5064.34163558735;4905.166121759238;4943.134951383566;4947.381577302837;4980.551750342486;5161.66449180802 12:56:33 INFO - PROCESS | 1956 | |4;prototype.html;306.2053969947985;317.94753865612176;319.870452466751;324.33025801719464;327.04176762087076;4597.747123908183;5374.6775193488365;5375.516203541681;5385.299911011568;5409.459054094588;4810.687305325151;4871.269309603561;5281.551068159201;5303.787848486066;5394.514493695567;3498.860045598173;3695.039289784662;3845.0964023454467;3969.880903572883;4043.3934909763625;3942.7042971777087;3994.5406278277933;3997;4014.4179094031347;4082.142750022499;3384.6615338466204;3996.6403023727858;4002.239574480839;4006.779627120507;4061.8375264989363;3578.6063533011347;3753.286875493675;3785.5268091488565;3908.7654740715648;3941.975086477522;3045.0560326298696;3403.5405220295193;3476.548048753671;3478.0783092480515;3493.7030352420074;4301.720388174758;4385.684294711606;5074.619403544749;5075.010372977271;5118.692878427306;4027.617376349242;4243.936340954888;4618.6997845140295;4635.281531362644;4736.673731355226;4385.407969924051;5183.222516622499;5279.392869819982;5463.344398672185;5487.890242195173;3289.917752056194;3352.8935451301254;3871.4192871069286;3896.7467114637743;3903.8093381518624;5391.92410921794;5819.359870414293;5850.619709718812;5872.678647304365;5912.320083190445;3137.8587963541695;3607.6572725591027;3818.3890577507464;3821.7133714970932;3825.4835597194024;3310.784798988058;3339.664134346281;3908;3909.1204478992;3912.7847968361443;3977.5226972763453;4110.7328023678965;4344.652427805768;4505.81976720934;4582.266837306015;4653.557911998355;5114.948850511447;5247.422783493813;5466.289382380265;5546.94608024474;1252.3487786351047;1277.6422601671547;1334.4595438847282;1352;1356.379126943313;736.0799001248439;756.9394448444111;802.6789284286332;814.9470284431493;839.6557411461272;4881.975590122027;5061.6962982221185;5073.452596957964;5082.288479612857;5105.33630628016;5278.9472105278455;5315.441878602768;5488.725563721798;5640.802571909963;5793.304803423616;4286.914261714748;5093.567046800989;5122.487751224848;5128.564072053842;5135.35808023997;2678.9196324110308;2771.798572648594;2990.355304582471;3003.459377312061;3017.502112151514;5740.56945729072;5875.20684707559;5888.558358123158;5894.587378883437;5910.822675319747;5616.606837521416;5803.142994241886;5888.411158884077;5949.226600541989;5960.523158147338;4915.926261106087;5865;5866.413358664185;5893.996041345963;5923.614965027259;4995.475475075064;5196.922046169235;5499.9175012375645;5537.972310138423;5809.215755872903;5068.960863022998;5643.943560564342;5663.235463212413;5696.886062278649;5716.485516303553;4989.401271847401;5312;5531.778728850801;5840.094785308284;5928.881422371443;2849;2908.1130255272346;3209.5506629072415;3348.430766769606;3387.6781705737917;4040.5151381834366;4160.729552579072;4694.793447079867;4781.7609119543185;4846.903061938812;3915.099527108724;4173.641892330824;4573.451185857585;4748.406449193851;4761.381020467317;4302.010102978525;4410.713303635253;4555.612772914339;4647.6289494598495;4692.249240121564;4093.5087789465456;4178.603032711888;4428.140181124;4506.526814684542;4540.750258735669;4763.7618119094595;5053.545180933734;5070.847874563769;5501.707098831691;5602.85992850182;4518.525554816697;5238.790448382175;5484.945150548443;5495.395506494203;5502.642328248651 12:56:33 INFO - PROCESS | 1956 | |5;yui.html;661.8279247395682;665.7151697224361;666.929972352903;677.4614181725527;746.6042997211479;13171.670708232286;14880.330385132667;15492.380304787795;15629.140397278132;15686.764698529503;14520.636984075403;14802.481913133042;14826.481073162442;14849.703005939875;14855.554333370017;728.4973368375804;751.357589261182;915.2723584750123;920.9677661281856;933.7105497295826;907.691384929124;939.464505232018;940.7883226274067;943.3726571829725;943.9764005899839;981.4160574458186;985.5367977050774;987.6641941739807;987.8172538080478;990.6384169778052;758.3478208740452;956.1012648110823;965.9758506037335;978.4862946952871;979.0992287095853;726.7347418192375;729.0558726449261;730.0946825935854;730.3572855886786;731.9670614822346;14957;15011.099334039993;15025;15037.924810375987;15044;12187;12256.877431225663;12357.011439084852;12472.650959262948;12630.49478020878;10771.78456430867;10863.239573229877;10979.505922233519;10985.395803230818;11300.717482062932;3622.8913132606062;3702.629737026276;3738.6822120119823;3759.906002349936;3908.179282350681;70244;71439;71455.64272178606;75777.24222757758;75885.48229035444;9437.103475169848;9510.762230944281;9516.619335226513;9553.331266811256;9751.902480975099;21563.353099407042;21813.127474901143;21824.454388640093;21845.235416760337;22080.668789968164;7081.362677359062;7436.628168591549;7475;7546.849063018819;7631;4064.695147863922;4089;4098.344264917599;4159.334506478949;4389.692721509464;3452.7755695879687;3585.4980302757635;3590.4075827488173;3871.593482684334;4068.267711811843;703.3142685881224;710.2081179484837;710.6482291265856;713.7644577289482;714.8320144765976;513.3839392728665;561.301180030856;577.8064348443235;580.2225018475264;618.2241287184554;7184.425245980414;7187.101612298463;7255.201927787939;7290.5625662460425;7333.559986400833;513.2967834067351;575.5884542552095;578.3002566894105;622.3185611755121;627.7740013595102;3287.983560082232;3705.6109108543756;3854.614538546179;3858.749181303206;3997.340438827619;79151.0212244687;93687;93801.53099234597;93859;96926.51536742272;69447.95828062583;74365.62817185988;75260.62369688226;75356.62321688466;76054.61972690102;63353.373012898395;68224.3177568218;68448.63102737918;68513.97229041568;68655.97016044763;7772;8249.092599814136;8329.208725171098;8449.619767110371;8485.787855303666;33665.326693466486;37293.627063729014;38692.64575739836;42595.36106958336;43298.78350608227;58778;61783.38216617776;61945.07082393858;62093.37906621057;62527.6873615629;9361.906380936103;9423.38747981379;10743.203016471336;11014.284071535325;11104.167187461135;9773.31586788918;12165;12376.504939802307;12379.947704445223;12788.9129424;11414.429278535874;11620.360880151673;11711.648650540497;11801.468933897824;11870.228435151688;9704.786901637295;11382.886171138183;11478.081753459535;11698.70753231176;11873.346965916611;11211.159163062966;11223.270487418291;11224.045956093823;11490.253133546294;12288.262704237774;9409.670661527087;9533.189678877374;9711.611535538499;9878.506074696093;10129.594816207564;9254.259659227111;9570.47362395075;9658.468784216935;9777.706668799947;9906.596933672343 12:56:33 INFO - PROCESS | 1956 | __end_tp_report 12:56:33 INFO - PROCESS | 1956 | __start_cc_report 12:56:33 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1153 12:56:33 INFO - PROCESS | 1956 | __end_cc_report 12:56:33 INFO - PROCESS | 1956 | __startTimestamp1462996593129__endTimestamp 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | ------- Summary: start ------- 12:56:33 INFO - PROCESS | 1956 | Number of tests: 6 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | [#0] dojo.html Cycles:165 Average:5803.56 Median:6060.21 stddev:648.53 (10.7%) stddev-sans-first:623.74 12:56:33 INFO - PROCESS | 1956 | Values: 3452.6 3454.3 3462.0 3473.2 3526.2 5671.8 5702.9 5734.2 5789.5 5824.7 4860.3 5737.3 5813.7 5852.3 5904.1 5152.3 5162.1 5220.8 5222.1 5312.3 5304.8 5315.0 5339.8 5355.8 5391.6 5150.7 5177.1 5183.8 5257.8 5264.9 5223.6 5244.0 5250.3 5251.1 5252.1 5128.2 5169.0 5185.6 5199.9 5218.0 5625.9 5698.4 5701.5 5708.0 5710.3 6230.9 6235.5 6284.0 6290.9 6298.9 6216.1 6340.8 6349.7 6360.9 6366.6 5763.6 5845.8 5896.2 5896.4 5949.3 6254.4 6282.1 6295.9 6368.9 6378.0 6257.5 6270.4 6271.0 6273.4 6295.9 6221.3 6369.7 6379.8 6380.3 6390.5 6183.6 6258.2 6272.2 6338.5 6348.4 6282.5 6317.7 6331.7 6335.5 6352.0 4877.4 4926.9 4936.7 5024.5 5073.5 4507.1 4520.2 4553.6 4555.9 4562.0 5761.3 5791.4 5803.8 5824.2 5872.9 6338.3 6376.4 6385.9 6394.2 6398.6 5758.3 5775.3 5813.8 5821.6 5832.2 5309.8 5423.1 5453.7 5462.2 5481.0 6372.2 6384.7 6392.3 6402.5 6406.8 6293.1 6318.5 6329.5 6332.3 6352.7 5386.4 6237.3 6237.8 6276.0 6362.9 6261.3 6316.3 6325.4 6326.1 6331.7 6320.7 6339.5 6341.5 6345.6 6350.0 6005.4 6099.6 6148.4 6165.8 6260.0 5181.7 6045.0 6169.5 6218.7 6233.9 5833.2 6031.4 6055.1 6067.2 6103.9 6110.1 6140.1 6142.6 6221.1 6233.8 6060.2 6090.3 6092.6 6107.4 6140.1 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | [#1] ext.html Cycles:180 Average:69590.22 Median:37740.22 stddev:154933.56 (410.5%) stddev-sans-first:155281.33 12:56:33 INFO - PROCESS | 1956 | Values: 491.6 521.3 523.5 526.2 556.5 41712.4 43189.9 43268.5 43411.0 45528.0 43402.0 43676.1 43802.6 43945.8 44034.8 8522.9 8719.0 9662.2 9846.5 9881.7 7824.0 7957.6 8097.9 8179.8 8182.3 7993.9 8001.4 8022.9 8023.6 8024.9 8614.4 11472.9 11594.2 11790.6 11824.9 10499.5 11352.7 11683.1 11749.7 11751.4 40507.8 40892.8 40936.0 40938.8 40965.4 3192.5 3387.8 3398.6 3414.4 3749.6 27756.9 29070.0 29860.1 30263.7 30496.0 2652.6 2745.0 2748.7 2759.4 2795.5 481571.0 484314.0 484407.0 484851.0 484906.0 35493.0 36345.6 61148.0 61257.7 62034.7 37707.4 37723.0 37757.4 37777.4 37859.0 15246.4 15397.4 15412.3 15419.2 15422.9 11676.6 11858.2 11875.6 12075.2 12086.3 7993.6 8195.8 8197.3 8257.4 8301.5 2813.3 3191.9 3199.2 3239.3 3271.2 956.4 1045.3 1071.4 1071.7 1099.2 28742.6 29431.8 29708.9 29927.4 30760.2 1107.3 1113.2 1129.4 1147.7 1206.9 15389.6 15493.2 15624.3 15657.6 15876.7 657589.0 660872.0 662564.0 662670.0 664086.0 60671.7 60767.4 61390.0 61440.4 61500.1 1339.1 1373.7 1480.8 1497.4 1514.6 27996.4 30486.8 31334.4 31877.5 32744.5 55916.0 56311.7 56535.7 56621.9 56668.7 570625.0 576121.0 577859.0 578520.0 578908.0 26765.9 27028.7 27836.0 27859.0 28860.9 45847.5 46536.3 46899.8 47509.0 48144.0 36815.6 37849.6 38137.0 38493.0 38869.4 37504.4 37669.6 37828.6 38612.0 38800.8 37362.6 38270.0 38304.0 39064.6 41007.6 26779.3 30969.4 32348.5 33594.3 34105.3 24702.0 24906.4 24919.5 25180.2 25337.0 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | [#2] jquery.html Cycles:180 Average:34242.81 Median:13981.63 stddev:98567.13 (705.0%) stddev-sans-first:98810.76 12:56:33 INFO - PROCESS | 1956 | Values: 337.3 351.4 390.9 410.2 419.2 18933.5 20924.8 20976.4 21013.4 21026.9 18522.9 19050.0 20469.4 21186.3 21461.4 5042.2 5873.8 6114.5 6128.4 6132.0 5275.4 6062.4 6073.5 6099.8 6109.0 5869.1 5946.2 6004.4 6005.9 6044.3 5926.5 5949.5 6029.4 6043.2 6052.5 5139.5 5253.7 5256.4 5260.4 5261.3 25579.0 25820.5 26055.5 26104.7 26426.1 2269.8 2317.4 2362.5 2384.3 2822.8 7026.7 7051.4 10166.4 11598.3 15361.8 1731.8 1737.1 1741.2 1783.9 1803.3 116802.0 117897.4 118994.0 119513.2 121935.0 10423.6 10473.4 10624.8 10638.8 10711.5 13235.9 13300.8 13353.6 13354.8 13359.7 8439.2 8645.0 8670.0 8773.2 8780.7 8652.0 8671.7 8677.0 8795.9 8824.2 6493.7 6612.5 6638.3 6650.2 6665.2 1698.2 1830.6 1849.5 1884.8 1919.5 829.4 941.8 957.5 974.5 1083.8 20342.0 21135.0 21326.9 21469.9 21617.1 964.9 969.9 975.8 978.4 1051.3 9344.7 11295.6 11307.5 11387.8 11397.3 581622.0 582613.0 590628.5 594720.0 609987.0 118146.2 118275.4 121042.0 121133.4 122094.0 1034.0 1058.5 1060.3 1062.2 1086.0 12903.8 17816.9 18642.0 19144.7 19185.7 47180.8 49070.5 49157.8 50386.0 50510.7 66460.7 67733.5 68031.0 68279.7 68520.7 7943.2 8015.5 8075.6 8141.8 8165.8 12073.5 12325.9 12482.9 12634.4 12899.7 9956.7 10307.4 10673.1 10836.0 11191.1 10678.5 10782.2 10827.0 10965.5 11072.6 9706.0 11155.0 11198.3 11523.5 11608.2 6794.7 7289.3 7855.7 7968.6 8207.7 10159.5 10973.1 11225.7 11309.7 11381.8 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | [#3] mootools.html Cycles:180 Average:5727.90 Median:4750.33 stddev:3262.42 (68.7%) stddev-sans-first:3255.59 12:56:33 INFO - PROCESS | 1956 | Values: 1430.1 1608.4 1645.9 1891.1 2009.3 7863.3 8526.3 8759.1 8779.0 8889.8 8486.0 8675.7 8679.3 8691.3 8695.3 2127.1 2359.0 2419.9 2449.2 2466.3 2459.6 2477.8 2485.9 2486.9 2487.3 1987.6 2195.2 2449.9 2450.9 2455.2 2107.4 2349.4 2395.1 2431.1 2431.3 2428.2 2447.8 2448.0 2453.4 2453.6 8762.4 8818.5 8824.2 8840.8 8862.3 5105.8 5420.4 5423.3 5428.4 5436.5 3423.3 3499.6 3579.4 3580.9 3589.5 1009.8 1049.9 1055.8 1055.9 1062.1 12146.2 12170.7 12172.6 12184.6 12202.1 4822.5 4861.7 4962.5 4965.2 4967.6 10246.0 10270.6 10279.4 10289.0 10305.0 5457.5 5493.3 5499.8 5501.7 5512.5 4247.5 4413.3 4590.8 4597.0 4623.1 3357.4 4072.5 4140.4 4143.6 4152.5 1237.6 1286.4 1311.6 1349.5 1365.2 793.1 855.4 915.8 918.5 939.3 4749.8 5884.2 6006.4 6031.5 6150.7 456.0 462.9 470.3 472.6 477.7 3904.7 3982.4 4050.5 4060.5 4063.5 10567.5 10949.3 10965.6 10974.2 10977.9 10217.5 10925.6 10937.7 10957.7 10972.6 8143.6 8277.4 8324.4 8330.1 8416.6 5021.5 5862.7 5907.9 5971.3 5979.1 9694.2 9999.9 10015.9 10117.9 10171.9 9154.8 9973.4 10083.2 10425.9 10445.6 5583.7 6728.6 7126.0 7209.9 7550.2 7834.3 8517.2 8539.6 8686.6 8687.8 6683.9 7536.7 7624.4 7689.2 7840.4 6488.9 7446.8 7472.4 7630.2 7630.6 6840.6 7094.5 7104.8 7105.1 7118.5 4750.9 4845.5 4899.4 4984.1 5064.3 4905.2 4943.1 4947.4 4980.6 5161.7 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | [#4] prototype.html Cycles:180 Average:4333.37 Median:4693.52 stddev:1360.96 (29.0%) stddev-sans-first:1330.79 12:56:33 INFO - PROCESS | 1956 | Values: 306.2 317.9 319.9 324.3 327.0 4597.7 5374.7 5375.5 5385.3 5409.5 4810.7 4871.3 5281.6 5303.8 5394.5 3498.9 3695.0 3845.1 3969.9 4043.4 3942.7 3994.5 3997.0 4014.4 4082.1 3384.7 3996.6 4002.2 4006.8 4061.8 3578.6 3753.3 3785.5 3908.8 3942.0 3045.1 3403.5 3476.5 3478.1 3493.7 4301.7 4385.7 5074.6 5075.0 5118.7 4027.6 4243.9 4618.7 4635.3 4736.7 4385.4 5183.2 5279.4 5463.3 5487.9 3289.9 3352.9 3871.4 3896.7 3903.8 5391.9 5819.4 5850.6 5872.7 5912.3 3137.9 3607.7 3818.4 3821.7 3825.5 3310.8 3339.7 3908.0 3909.1 3912.8 3977.5 4110.7 4344.7 4505.8 4582.3 4653.6 5114.9 5247.4 5466.3 5546.9 1252.3 1277.6 1334.5 1352.0 1356.4 736.1 756.9 802.7 814.9 839.7 4882.0 5061.7 5073.5 5082.3 5105.3 5278.9 5315.4 5488.7 5640.8 5793.3 4286.9 5093.6 5122.5 5128.6 5135.4 2678.9 2771.8 2990.4 3003.5 3017.5 5740.6 5875.2 5888.6 5894.6 5910.8 5616.6 5803.1 5888.4 5949.2 5960.5 4915.9 5865.0 5866.4 5894.0 5923.6 4995.5 5196.9 5499.9 5538.0 5809.2 5069.0 5643.9 5663.2 5696.9 5716.5 4989.4 5312.0 5531.8 5840.1 5928.9 2849.0 2908.1 3209.6 3348.4 3387.7 4040.5 4160.7 4694.8 4781.8 4846.9 3915.1 4173.6 4573.5 4748.4 4761.4 4302.0 4410.7 4555.6 4647.6 4692.2 4093.5 4178.6 4428.1 4506.5 4540.8 4763.8 5053.5 5070.8 5501.7 5602.9 4518.5 5238.8 5484.9 5495.4 5502.6 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - PROCESS | 1956 | [#5] yui.html Cycles:180 Average:17424.58 Median:31577.73 stddev:24079.30 (76.3%) stddev-sans-first:24113.95 12:56:33 INFO - PROCESS | 1956 | Values: 661.8 665.7 666.9 677.5 746.6 13171.7 14880.3 15492.4 15629.1 15686.8 14520.6 14802.5 14826.5 14849.7 14855.6 728.5 751.4 915.3 921.0 933.7 907.7 939.5 940.8 943.4 944.0 981.4 985.5 987.7 987.8 990.6 758.3 956.1 966.0 978.5 979.1 726.7 729.1 730.1 730.4 732.0 14957.0 15011.1 15025.0 15037.9 15044.0 12187.0 12256.9 12357.0 12472.7 12630.5 10771.8 10863.2 10979.5 10985.4 11300.7 3622.9 3702.6 3738.7 3759.9 3908.2 70244.0 71439.0 71455.6 75777.2 75885.5 9437.1 9510.8 9516.6 9553.3 9751.9 21563.4 21813.1 21824.5 21845.2 22080.7 7081.4 7436.6 7475.0 7546.8 7631.0 4064.7 4089.0 4098.3 4159.3 4389.7 3452.8 3585.5 3590.4 3871.6 4068.3 703.3 710.2 710.6 713.8 714.8 513.4 561.3 577.8 580.2 618.2 7184.4 7187.1 7255.2 7290.6 7333.6 513.3 575.6 578.3 622.3 627.8 3288.0 3705.6 3854.6 3858.7 3997.3 79151.0 93687.0 93801.5 93859.0 96926.5 69448.0 74365.6 75260.6 75356.6 76054.6 63353.4 68224.3 68448.6 68514.0 68656.0 7772.0 8249.1 8329.2 8449.6 8485.8 33665.3 37293.6 38692.6 42595.4 43298.8 58778.0 61783.4 61945.1 62093.4 62527.7 9361.9 9423.4 10743.2 11014.3 11104.2 9773.3 12165.0 12376.5 12379.9 12788.9 11414.4 11620.4 11711.6 11801.5 11870.2 9704.8 11382.9 11478.1 11698.7 11873.3 11211.2 11223.3 11224.0 11490.3 12288.3 9409.7 9533.2 9711.6 9878.5 10129.6 9254.3 9570.5 9658.5 9777.7 9906.6 12:56:33 INFO - PROCESS | 1956 | -------- Summary: end -------- 12:56:33 INFO - PROCESS | 1956 | 12:56:33 INFO - TEST-INFO | 1956: exit 0 12:56:33 INFO - TEST-OK | dromaeo_css | took 1115985ms 12:56:33 INFO - TEST-START | kraken 12:56:33 INFO - operating with platform_type : mac_ 12:56:33 INFO - Initialising browser for kraken test... 12:56:33 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmp_oOJwS/profile http://localhost:49675/getInfo.html) 12:56:35 INFO - PROCESS | 1961 | __metrics Screen width/height:1600/1200 12:56:35 INFO - PROCESS | 1961 | colorDepth:24 12:56:35 INFO - PROCESS | 1961 | Browser inner width/height: 1024/689 12:56:35 INFO - PROCESS | 1961 | __metrics 12:56:35 INFO - PROCESS | 1961 | 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" 12:56:35 ERROR - PROCESS | 1961 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:56:36 INFO - TEST-INFO | 1961: exit 0 12:56:36 INFO - Browser initialized. 12:56:36 INFO - Running cycle 1/1 for kraken test... 12:56:36 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/T/tmp_oOJwS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:56:48 INFO - PROCESS | 1963 | RSS: Main: 238927872 12:56:48 INFO - PROCESS | 1963 | 12:56:51 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49675/tests/kraken/driver.html?testName=audio-beat-detection) 12:56:52 INFO - PROCESS | 1963 | RSS: Main: 286507008 12:56:52 INFO - PROCESS | 1963 | 12:56:54 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49675/tests/kraken/driver.html?testName=audio-dft) 12:56:55 INFO - PROCESS | 1963 | RSS: Main: 295534592 12:56:55 INFO - PROCESS | 1963 | 12:56:59 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49675/tests/kraken/driver.html?testName=audio-fft) 12:56:59 INFO - PROCESS | 1963 | RSS: Main: 386072576 12:56:59 INFO - PROCESS | 1963 | 12:57:01 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49675/tests/kraken/driver.html?testName=audio-oscillator) 12:57:02 INFO - PROCESS | 1963 | RSS: Main: 283709440 12:57:02 INFO - PROCESS | 1963 | 12:57:04 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49675/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:57:05 INFO - PROCESS | 1963 | RSS: Main: 287264768 12:57:05 INFO - PROCESS | 1963 | 12:57:08 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49675/tests/kraken/driver.html?testName=imaging-darkroom) 12:57:08 INFO - PROCESS | 1963 | RSS: Main: 325509120 12:57:08 INFO - PROCESS | 1963 | 12:57:12 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49675/tests/kraken/driver.html?testName=imaging-desaturate) 12:57:12 INFO - PROCESS | 1963 | RSS: Main: 329367552 12:57:12 INFO - PROCESS | 1963 | 12:57:15 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49675/tests/kraken/driver.html?testName=json-parse-financial) 12:57:16 INFO - PROCESS | 1963 | RSS: Main: 329175040 12:57:16 INFO - PROCESS | 1963 | 12:57:18 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49675/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:57:18 INFO - PROCESS | 1963 | RSS: Main: 315580416 12:57:18 INFO - PROCESS | 1963 | 12:57:20 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:57:21 INFO - PROCESS | 1963 | RSS: Main: 322408448 12:57:21 INFO - PROCESS | 1963 | 12:57:23 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:57:24 INFO - PROCESS | 1963 | RSS: Main: 324165632 12:57:24 INFO - PROCESS | 1963 | 12:57:27 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:57:28 INFO - PROCESS | 1963 | RSS: Main: 325500928 12:57:28 INFO - PROCESS | 1963 | 12:57:30 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:57:31 INFO - PROCESS | 1963 | RSS: Main: 321048576 12:57:31 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:49675/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49675/tests/kraken/driver.html?testName=ai-astar) 12:57:33 INFO - PROCESS | 1963 | RSS: Main: 368058368 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | __start_tp_report 12:57:33 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 12:57:33 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 12:57:33 INFO - PROCESS | 1963 | |i|pagename|runs| 12:57:33 INFO - PROCESS | 1963 | |0;ai-astar;113;106;112;112;112;113;111;111;113;113 12:57:33 INFO - PROCESS | 1963 | |1;audio-beat-detection;152;117;142;241;141;142;139;115;170;114 12:57:33 INFO - PROCESS | 1963 | |2;audio-dft;189;188;186;184;191;257;209;184;184;182 12:57:33 INFO - PROCESS | 1963 | |3;audio-fft;80;74;58;125;57;61;57;59;109;55 12:57:33 INFO - PROCESS | 1963 | |4;audio-oscillator;94;99;133;79;79;78;103;81;85;100 12:57:33 INFO - PROCESS | 1963 | |5;imaging-gaussian-blur;87;83;86;83;86;83;87;83;85;83 12:57:33 INFO - PROCESS | 1963 | |6;imaging-darkroom;93;93;93;94;92;107;146;95;93;92 12:57:33 INFO - PROCESS | 1963 | |7;imaging-desaturate;104;133;84;85;86;84;84;84;84;85 12:57:33 INFO - PROCESS | 1963 | |8;json-parse-financial;60;61;61;59;60;58;61;119;58;59 12:57:33 INFO - PROCESS | 1963 | |9;json-stringify-tinderbox;62;68;74;74;70;67;68;69;69;72 12:57:33 INFO - PROCESS | 1963 | |10;stanford-crypto-aes;85;85;88;85;83;82;86;85;85;101 12:57:33 INFO - PROCESS | 1963 | |11;stanford-crypto-ccm;146;148;132;134;131;140;146;148;275;142 12:57:33 INFO - PROCESS | 1963 | |12;stanford-crypto-pbkdf2;206;225;167;167;165;164;164;173;167;166 12:57:33 INFO - PROCESS | 1963 | |13;stanford-crypto-sha256-iterative;68;68;67;67;68;65;66;64;67;67 12:57:33 INFO - PROCESS | 1963 | __end_tp_report 12:57:33 INFO - PROCESS | 1963 | __start_cc_report 12:57:33 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,2189 12:57:33 INFO - PROCESS | 1963 | __end_cc_report 12:57:33 INFO - PROCESS | 1963 | __startTimestamp1462996653674__endTimestamp 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | ------- Summary: start ------- 12:57:33 INFO - PROCESS | 1963 | Number of tests: 14 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.50 stddev:2.12 (1.9%) stddev-sans-first:2.19 12:57:33 INFO - PROCESS | 1963 | Values: 113.0 106.0 112.0 112.0 112.0 113.0 111.0 111.0 113.0 113.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#1] audio-beat-detection Cycles:10 Average:147.30 Median:142.00 stddev:37.35 (26.3%) stddev-sans-first:39.57 12:57:33 INFO - PROCESS | 1963 | Values: 152.0 117.0 142.0 241.0 141.0 142.0 139.0 115.0 170.0 114.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#2] audio-dft Cycles:10 Average:195.40 Median:188.50 stddev:22.98 (12.2%) stddev-sans-first:24.26 12:57:33 INFO - PROCESS | 1963 | Values: 189.0 188.0 186.0 184.0 191.0 257.0 209.0 184.0 184.0 182.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#3] audio-fft Cycles:10 Average:73.50 Median:58.50 stddev:24.60 (42.1%) stddev-sans-first:25.98 12:57:33 INFO - PROCESS | 1963 | Values: 80.0 74.0 58.0 125.0 57.0 61.0 57.0 59.0 109.0 55.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#4] audio-oscillator Cycles:10 Average:93.10 Median:80.00 stddev:17.03 (21.3%) stddev-sans-first:18.06 12:57:33 INFO - PROCESS | 1963 | Values: 94.0 99.0 133.0 79.0 79.0 78.0 103.0 81.0 85.0 100.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#5] imaging-gaussian-blur Cycles:10 Average:84.60 Median:85.50 stddev:1.78 (2.1%) stddev-sans-first:1.66 12:57:33 INFO - PROCESS | 1963 | Values: 87.0 83.0 86.0 83.0 86.0 83.0 87.0 83.0 85.0 83.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:93.00 stddev:16.83 (18.1%) stddev-sans-first:17.67 12:57:33 INFO - PROCESS | 1963 | Values: 93.0 93.0 93.0 94.0 92.0 107.0 146.0 95.0 93.0 92.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#7] imaging-desaturate Cycles:10 Average:91.30 Median:84.00 stddev:15.90 (18.9%) stddev-sans-first:16.18 12:57:33 INFO - PROCESS | 1963 | Values: 104.0 133.0 84.0 85.0 86.0 84.0 84.0 84.0 84.0 85.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:60.00 stddev:18.80 (31.3%) stddev-sans-first:19.83 12:57:33 INFO - PROCESS | 1963 | Values: 60.0 61.0 61.0 59.0 60.0 58.0 61.0 119.0 58.0 59.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:69.50 stddev:3.56 (5.1%) stddev-sans-first:2.62 12:57:33 INFO - PROCESS | 1963 | Values: 62.0 68.0 74.0 74.0 70.0 67.0 68.0 69.0 69.0 72.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:85.00 stddev:5.34 (6.3%) stddev-sans-first:5.63 12:57:33 INFO - PROCESS | 1963 | Values: 85.0 85.0 88.0 85.0 83.0 82.0 86.0 85.0 85.0 101.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:146.00 stddev:42.94 (29.4%) stddev-sans-first:45.44 12:57:33 INFO - PROCESS | 1963 | Values: 146.0 148.0 132.0 134.0 131.0 140.0 146.0 148.0 275.0 142.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:167.00 stddev:21.24 (12.7%) stddev-sans-first:19.64 12:57:33 INFO - PROCESS | 1963 | Values: 206.0 225.0 167.0 167.0 165.0 164.0 164.0 173.0 167.0 166.0 12:57:33 INFO - PROCESS | 1963 | 12:57:33 INFO - PROCESS | 1963 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:1.34 (2.0%) stddev-sans-first:1.33 12:57:33 INFO - PROCESS | 1963 | Values: 68.0 68.0 67.0 67.0 68.0 65.0 66.0 64.0 67.0 67.0 12:57:33 INFO - PROCESS | 1963 | -------- Summary: end -------- 12:57:33 INFO - PROCESS | 1963 | 12:57:34 INFO - TEST-INFO | 1963: exit 0 12:57:34 INFO - TEST-OK | kraken | took 60848ms 12:57:34 INFO - SUITE-END | took 1176s 12:57:34 INFO - Completed test suite (00:19:37) 12:57:34 INFO - javascript benchmark 12:57:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3452.5856897172325, 3454.3091381723652, 3461.982690086549, 3473.218525831687, 3526.241858000531, 5671.7731290748325, 5702.942970570293, 5734.197212390269, 5789.4500022497905, 5824.708764561776, 4860.319555262257, 5737.340205876326, 5813.6802475863815, 5852.326982397026, 5904.0553511438175, 5152.304438900756, 5162.096633089194, 5220.765065572039, 5222.112240919034, 5312.335958005249, 5304.814331498398, 5314.990151871132, 5339.759710813023, 5355.839324820262, 5391.595630327722, 5150.690958542499, 5177.094008548489, 5183.766730497136, 5257.842264732064, 5264.947350526503, 5223.608229382811, 5244.0, 5250.264962905197, 5251.133562962106, 5252.133397989327, 5128.230765385185, 5168.974155129238, 5185.559227465632, 5199.896002079937, 5218.0, 5625.887482250332, 5698.401667824902, 5701.486866182064, 5708.001099807559, 5710.343310519302, 6230.937690623081, 6235.469985051276, 6284.0, 6290.905636415458, 6298.937010629881, 6216.067589861512, 6340.841478963062, 6349.714262858182, 6360.936390636127, 6366.554341196118, 5763.596548241583, 5845.766169353178, 5896.174535565025, 5896.38088000758, 5949.286085669704, 6254.405831446005, 6282.057691346243, 6295.874082518416, 6368.872622547523, 6378.0, 6257.530685198628, 6270.404311590483, 6271.0, 6273.37266273369, 6295.905561416583, 6221.253449585988, 6369.745210191633, 6379.776707815204, 6380.266269378988, 6390.520710946697, 6183.5671502994355, 6258.217722784652, 6272.247330320391, 6338.524610654219, 6348.428641422294, 6282.528810339243, 6317.652529110851, 6331.68341582919, 6335.524835637346, 6351.96824015877, 4877.3903262092235, 4926.876828079255, 4936.728479933567, 5024.472430394756, 5073.518015788494, 4507.121111383314, 4520.18636645407, 4553.635709143261, 4555.908881822345, 4562.041971186021, 5761.337446193657, 5791.391903850119, 5803.82588522345, 5824.213731146241, 5872.882542349214, 6338.271098823602, 6376.39424254695, 6385.936140638627, 6394.1687580614225, 6398.584092034003, 5758.280214973129, 5775.306963164448, 5813.82558523245, 5821.592488525847, 5832.154337621106, 5309.814156504503, 5423.132298832168, 5453.727313634382, 5462.20797984298, 5481.0, 6372.203474565679, 6384.744610215632, 6392.296847346881, 6402.455791257796, 6406.775762848185, 6293.087502312231, 6318.494520438446, 6329.49364050884, 6332.271788744353, 6352.682365881595, 5386.407495175449, 6237.282712488122, 6237.812865614039, 6275.96862015687, 6362.872742545216, 6261.342559031418, 6316.336784637547, 6325.367463253822, 6326.082718005955, 6331.683415829098, 6320.6839658016, 6339.524535659936, 6341.460975817107, 6345.587536810092, 6350.0, 6005.36943620931, 6099.612447337931, 6148.354422785722, 6165.753369865155, 6259.96870015647, 5181.689098654093, 6045.0327947528185, 6169.537284703575, 6218.720157592828, 6233.937660623335, 5833.212516310244, 6031.36670649593, 6055.122007308827, 6067.180930574316, 6103.908441373472, 6110.1445797587485, 6140.105498721277, 6142.617910969996, 6221.09794079865, 6233.750649974132, 6060.212172417558, 6090.291944953066, 6092.573519853568, 6107.358727333743, 6140.109684095871], "value": 5762.802528545468, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.6435584201452, 521.2780299285489, 523.4922125538228, 526.2185654303361, 556.5436342199393, 41712.37431438527, 43189.92025199372, 43268.4856030039, 43411.0, 45528.0, 43402.0, 43676.12647747043, 43802.561974380245, 43945.7802710986, 44034.77982610082, 8522.914770852274, 8719.0, 9662.178714809268, 9846.458444785534, 9881.654142105028, 7823.960880195619, 7957.641906114239, 8097.919020809775, 8179.754607361789, 8182.263596276334, 7993.920060799404, 8001.439899207058, 8022.879656805153, 8023.638936247883, 8024.9197508025045, 8614.396992210548, 11472.885271147306, 11594.246373985665, 11790.646280611509, 11824.93575578202, 10499.475026248658, 11352.659420217407, 11683.123765717604, 11749.706257343549, 11751.353675547842, 40507.7974610128, 40892.79553602243, 40936.0, 40938.795306023574, 40965.385519217234, 3192.4732419150814, 3387.762856600039, 3398.640543782507, 3414.382815131872, 3749.585824567657, 27756.861215693792, 29070.0, 29860.10419687413, 30263.69736302631, 30496.0, 2652.5755879059257, 2745.0, 2748.725127487235, 2759.365345970438, 2795.53873610856, 481571.0, 484314.0, 484407.0, 484851.0, 484905.99999999645, 35493.0, 36345.636543634755, 61148.0, 61257.69371153205, 62034.68982655058, 37707.434388484195, 37723.0, 37757.433638495444, 37777.43333849994, 37859.0, 15246.390144394321, 15397.384104635914, 15412.306446209948, 15419.151946643042, 15422.922885385726, 11676.591319303783, 11858.169928105122, 11875.584354547549, 12075.215111017756, 12086.335251561248, 7993.640286187136, 8195.754127376189, 8197.262246397853, 8257.42198046143, 8301.460405073773, 2813.310738868949, 3191.888283910052, 3199.168216263788, 3239.2549713566013, 3271.214908422009, 956.4213650741201, 1045.29442626227, 1071.373246650718, 1071.6838532632892, 1099.2074554940452, 28742.568861467094, 29431.76250612431, 29708.851455742584, 29927.401451971273, 30760.230994224876, 1107.346665467362, 1113.1984970821159, 1129.4465711801158, 1147.7302833834042, 1206.9155159138952, 15389.615259618375, 15493.225338733018, 15624.265753493093, 15657.608559786097, 15876.682466350609, 657589.0, 660872.0, 662564.0, 662670.0, 664086.0, 60671.69664151651, 60767.39232607706, 61390.0, 61440.38559614347, 61500.077498837556, 1339.0961101256612, 1373.7115205806892, 1480.8445113263167, 1497.3561368611602, 1514.5683480208088, 27996.44007119887, 30486.847565762917, 31334.373312534077, 31877.521837172924, 32744.508832367057, 55916.0, 56311.71844140753, 56535.71732141313, 56621.86756264934, 56668.71665641645, 570625.0, 576121.0, 577859.0, 578520.0, 578908.0, 26765.86617066902, 27028.729712703407, 27836.0, 27859.0, 28860.855695721388, 45847.47276299307, 46536.301955471376, 46899.765501172275, 47509.0, 48144.0, 36815.63184368122, 37849.62150378461, 38137.0, 38493.0, 38869.41695874621, 37504.43743343907, 37669.62330376661, 37828.621713783614, 38612.034699131626, 38800.80599596984, 37362.626373737, 38270.0, 38304.0, 39064.6093539061, 41007.58992410157, 26779.33051673724, 30969.380612387176, 32348.514772277966, 33594.32811343808, 34105.31789364149, 24702.0, 24906.377340566632, 24919.50482970955, 25180.24459266225, 25337.0], "value": 18778.94250428268, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.29841928788125, 351.40787772603164, 390.8866428735667, 410.15508053409957, 419.2139737991266, 18933.526661833443, 20924.849133297663, 20976.370708878738, 21013.36959891202, 21026.89486552565, 18522.907385463055, 19050.0, 20469.385918422435, 21186.25848095317, 21461.356159315208, 5042.243663450475, 5873.794417195389, 6114.541409394291, 6128.417800308964, 6132.018876979684, 5275.419703832576, 6062.393760623946, 6073.544484163684, 6099.7865074722395, 6109.022556390978, 5869.060950247961, 5946.197263369433, 6004.399560044004, 6005.87988240235, 6044.304904935922, 5926.496247818941, 5949.494292985101, 6029.366916473773, 6043.153958445821, 6052.485538729214, 5139.460356662534, 5253.658512196733, 5256.448072952361, 5260.368755749297, 5261.289725886994, 25578.97684092634, 25820.483590328276, 26055.478890422273, 26104.73895261061, 26426.07508737185, 2269.8297627677825, 2317.4090606895134, 2362.4684445999683, 2384.284714585635, 2822.8024038317326, 7026.683799229047, 7051.400630946325, 10166.440845753481, 11598.304101753838, 15361.846381536154, 1731.8268173182707, 1737.148797089417, 1741.1990484377202, 1783.8840475369188, 1803.3417802502124, 116802.0, 117897.41051294774, 118993.99999999914, 119513.20730189054, 121935.0, 10423.5830566778, 10473.423961682181, 10624.840627390595, 10638.840417393745, 10711.517981690842, 13235.867641323655, 13300.800487992688, 13353.599392018255, 13354.799678004838, 13359.666008349868, 8439.240468357877, 8645.0, 8669.956650216709, 8773.166549177946, 8780.736577902673, 8651.95674021626, 8671.6531338747, 8676.956615216883, 8795.912040879637, 8824.205821476098, 6493.740250390026, 6612.5040621952585, 6638.302978187317, 6650.235222949326, 6665.233498147678, 1698.1678977301033, 1830.5606654402848, 1849.4729002234299, 1884.7738271407245, 1919.4913347962802, 829.4401279136558, 941.7975135345933, 957.4925289596385, 974.45917515778, 1083.7724077943562, 20342.0, 21135.0, 21326.893365533073, 21469.892650536647, 21617.135314587555, 964.9131578157859, 969.9272554558436, 975.7804493988796, 978.3738407419257, 1051.3113910388709, 9344.719658410258, 11295.604653837076, 11307.483505379347, 11387.829182562102, 11397.316161030365, 581622.0, 582613.0, 590628.5154316468, 594720.0, 609987.0, 118146.22780658297, 118275.40862295634, 121042.0, 121133.39433302777, 122093.99999999822, 1033.9793204135874, 1058.513083981362, 1060.2631171336002, 1062.245805478119, 1086.044281032702, 12903.806442903177, 17816.91091544534, 18642.0, 19144.712829307293, 19185.712214317075, 47180.7640961793, 49070.509294906595, 49157.77105572389, 50385.992280154926, 50510.74744626253, 66460.66769666121, 67733.50130729745, 68031.0, 68279.65860170667, 68520.6573967127, 7943.24539168778, 8015.519068855887, 8075.5558444286125, 8141.755747327589, 8165.836683266421, 12073.456694448596, 12325.93837030845, 12482.937585312015, 12634.431450584561, 12899.742005159656, 9956.701298961043, 10307.381557106597, 10673.092787113183, 10836.0, 11191.104711622887, 10678.519466624177, 10782.245242832927, 10827.025567698629, 10965.451727413756, 11072.557097716, 9705.951470242604, 11155.0, 11198.328100313682, 11523.481443335239, 11608.24546404481, 6794.694238759169, 7289.343959043499, 7855.725049623465, 7968.561729104723, 8207.71273005442, 10159.492025398848, 10973.079711050694, 11225.71935701614, 11309.71725706864, 11381.829272561085], "value": 9547.573499617929, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1430.1490613085211, 1608.3807734022403, 1645.8847880648357, 1891.1017266798272, 2009.3469622372722, 7863.252990965856, 8526.275266602339, 8759.080296568864, 8779.0, 8889.777755556115, 8486.024107227671, 8675.69635062773, 8679.349048821348, 8691.30469562435, 8695.347848911326, 2127.117246342766, 2359.0210062823908, 2419.9395015124633, 2449.155041510685, 2466.2847774145475, 2459.606462965926, 2477.838940468873, 2485.875706214691, 2486.9129580464687, 2487.340854673513, 1987.5826076523874, 2195.242641288753, 2449.9142530011454, 2450.9387265318333, 2455.226603619863, 2107.399391173516, 2349.4361353275262, 2395.101836811196, 2431.1004928176512, 2431.319230615413, 2428.162284012013, 2447.8041756659422, 2447.9877600612062, 2453.4479742058074, 2453.6196889482158, 8762.430442021312, 8818.470891746452, 8824.161704638049, 8840.778980525474, 8862.335324850663, 5105.795768169269, 5420.430854760272, 5423.3491980962535, 5428.37573679028, 5436.483534064258, 3423.3495635829113, 3499.632538583463, 3579.4093974494176, 3580.874669386585, 3589.4795254688183, 1009.7677534167183, 1049.8740151181908, 1055.767731099157, 1055.8680164979378, 1062.0866055192528, 12146.210496317799, 12170.695732606668, 12172.57395991136, 12184.573539926148, 12202.145849790519, 4822.517748225219, 4861.6596838220985, 4962.478939711278, 4965.155923493015, 4967.602591792648, 10246.0, 10270.589176432859, 10279.383237005804, 10289.022542858416, 10305.0, 5457.536109430664, 5493.313335833021, 5499.752511137009, 5501.724913754297, 5512.476314750093, 4247.511536173318, 4413.271810151289, 4590.770461476913, 4596.954030459653, 4623.052274283831, 3357.3788849063003, 4072.450219220427, 4140.420341152241, 4143.606357396043, 4152.522459917148, 1237.5915947737226, 1286.414681319997, 1311.6458556189966, 1349.4871948659643, 1365.215001374214, 793.1077537770009, 855.422589751915, 915.7619019055094, 918.4581097152578, 939.2908354192541, 4749.786259618326, 5884.235049443545, 6006.399360063959, 6031.487323577545, 6150.723217455135, 455.9749213793207, 462.9467611224752, 470.2945581627559, 472.6006524486803, 477.67279413601796, 3904.72666913319, 3982.38273067675, 4050.4936882889638, 4060.451839001697, 4063.532693740258, 10567.471626418648, 10949.343039417661, 10965.56137754497, 10974.231803773819, 10977.890221097687, 10217.540210690537, 10925.562977480971, 10937.726556835984, 10957.726056848483, 10972.615958441416, 8143.592820358958, 8277.420580559303, 8324.417290789708, 8330.125336839545, 8416.621252043673, 5021.49785021495, 5862.736176871966, 5907.881842363215, 5971.253593300838, 5979.1031345296815, 9694.17599504022, 9999.900001000187, 10015.899841001788, 10117.898821011695, 10171.898281017095, 9154.816903661756, 9973.401595904268, 10083.193344532274, 10425.9478702606, 10445.582176713151, 5583.720813959367, 6728.596284222962, 7126.0, 7209.891851622125, 7550.169481356937, 7834.3340816028995, 8517.233448989373, 8539.573021348784, 8686.609102590524, 8687.782805429915, 6683.933160668331, 7536.698532058876, 7624.351930086005, 7689.192634773268, 7840.411969102454, 6488.870222595428, 7446.776596701891, 7472.364848987897, 7630.16068232505, 7630.618469076635, 6840.555363901331, 7094.53885497441, 7104.786856394317, 7105.147382314155, 7118.537295075804, 4750.881227969328, 4845.515448455127, 4899.4365647950945, 4984.127777638972, 5064.34163558735, 4905.166121759238, 4943.134951383566, 4947.381577302837, 4980.551750342486, 5161.66449180802], "value": 4525.845767133596, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.2053969947985, 317.94753865612176, 319.870452466751, 324.33025801719464, 327.04176762087076, 4597.747123908183, 5374.6775193488365, 5375.516203541681, 5385.299911011568, 5409.459054094588, 4810.687305325151, 4871.269309603561, 5281.551068159201, 5303.787848486066, 5394.514493695567, 3498.860045598173, 3695.039289784662, 3845.0964023454467, 3969.880903572883, 4043.3934909763625, 3942.7042971777087, 3994.5406278277933, 3997.0, 4014.4179094031347, 4082.142750022499, 3384.6615338466204, 3996.6403023727858, 4002.239574480839, 4006.779627120507, 4061.8375264989363, 3578.6063533011347, 3753.286875493675, 3785.5268091488565, 3908.7654740715648, 3941.975086477522, 3045.0560326298696, 3403.5405220295193, 3476.548048753671, 3478.0783092480515, 3493.7030352420074, 4301.720388174758, 4385.684294711606, 5074.619403544749, 5075.010372977271, 5118.692878427306, 4027.617376349242, 4243.936340954888, 4618.6997845140295, 4635.281531362644, 4736.673731355226, 4385.407969924051, 5183.222516622499, 5279.392869819982, 5463.344398672185, 5487.890242195173, 3289.917752056194, 3352.8935451301254, 3871.4192871069286, 3896.7467114637743, 3903.8093381518624, 5391.92410921794, 5819.359870414293, 5850.619709718812, 5872.678647304365, 5912.320083190445, 3137.8587963541695, 3607.6572725591027, 3818.3890577507464, 3821.7133714970932, 3825.4835597194024, 3310.784798988058, 3339.664134346281, 3908.0, 3909.1204478992, 3912.7847968361443, 3977.5226972763453, 4110.7328023678965, 4344.652427805768, 4505.81976720934, 4582.266837306015, 4653.557911998355, 5114.948850511447, 5247.422783493813, 5466.289382380265, 5546.94608024474, 1252.3487786351047, 1277.6422601671547, 1334.4595438847282, 1352.0, 1356.379126943313, 736.0799001248439, 756.9394448444111, 802.6789284286332, 814.9470284431493, 839.6557411461272, 4881.975590122027, 5061.6962982221185, 5073.452596957964, 5082.288479612857, 5105.33630628016, 5278.9472105278455, 5315.441878602768, 5488.725563721798, 5640.802571909963, 5793.304803423616, 4286.914261714748, 5093.567046800989, 5122.487751224848, 5128.564072053842, 5135.35808023997, 2678.9196324110308, 2771.798572648594, 2990.355304582471, 3003.459377312061, 3017.502112151514, 5740.56945729072, 5875.20684707559, 5888.558358123158, 5894.587378883437, 5910.822675319747, 5616.606837521416, 5803.142994241886, 5888.411158884077, 5949.226600541989, 5960.523158147338, 4915.926261106087, 5865.0, 5866.413358664185, 5893.996041345963, 5923.614965027259, 4995.475475075064, 5196.922046169235, 5499.9175012375645, 5537.972310138423, 5809.215755872903, 5068.960863022998, 5643.943560564342, 5663.235463212413, 5696.886062278649, 5716.485516303553, 4989.401271847401, 5312.0, 5531.778728850801, 5840.094785308284, 5928.881422371443, 2849.0, 2908.1130255272346, 3209.5506629072415, 3348.430766769606, 3387.6781705737917, 4040.5151381834366, 4160.729552579072, 4694.793447079867, 4781.7609119543185, 4846.903061938812, 3915.099527108724, 4173.641892330824, 4573.451185857585, 4748.406449193851, 4761.381020467317, 4302.010102978525, 4410.713303635253, 4555.612772914339, 4647.6289494598495, 4692.249240121564, 4093.5087789465456, 4178.603032711888, 4428.140181124, 4506.526814684542, 4540.750258735669, 4763.7618119094595, 5053.545180933734, 5070.847874563769, 5501.707098831691, 5602.85992850182, 4518.525554816697, 5238.790448382175, 5484.945150548443, 5495.395506494203, 5502.642328248651], "value": 3908.4745180351283, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.8279247395682, 665.7151697224361, 666.929972352903, 677.4614181725527, 746.6042997211479, 13171.670708232286, 14880.330385132667, 15492.380304787795, 15629.140397278132, 15686.764698529503, 14520.636984075403, 14802.481913133042, 14826.481073162442, 14849.703005939875, 14855.554333370017, 728.4973368375804, 751.357589261182, 915.2723584750123, 920.9677661281856, 933.7105497295826, 907.691384929124, 939.464505232018, 940.7883226274067, 943.3726571829725, 943.9764005899839, 981.4160574458186, 985.5367977050774, 987.6641941739807, 987.8172538080478, 990.6384169778052, 758.3478208740452, 956.1012648110823, 965.9758506037335, 978.4862946952871, 979.0992287095853, 726.7347418192375, 729.0558726449261, 730.0946825935854, 730.3572855886786, 731.9670614822346, 14957.0, 15011.099334039993, 15025.0, 15037.924810375987, 15044.0, 12187.0, 12256.877431225663, 12357.011439084852, 12472.650959262948, 12630.49478020878, 10771.78456430867, 10863.239573229877, 10979.505922233519, 10985.395803230818, 11300.717482062932, 3622.8913132606062, 3702.629737026276, 3738.6822120119823, 3759.906002349936, 3908.179282350681, 70244.0, 71439.0, 71455.64272178606, 75777.24222757758, 75885.48229035444, 9437.103475169848, 9510.762230944281, 9516.619335226513, 9553.331266811256, 9751.902480975099, 21563.353099407042, 21813.127474901143, 21824.454388640093, 21845.235416760337, 22080.668789968164, 7081.362677359062, 7436.628168591549, 7475.0, 7546.849063018819, 7631.0, 4064.695147863922, 4089.0, 4098.344264917599, 4159.334506478949, 4389.692721509464, 3452.7755695879687, 3585.4980302757635, 3590.4075827488173, 3871.593482684334, 4068.267711811843, 703.3142685881224, 710.2081179484837, 710.6482291265856, 713.7644577289482, 714.8320144765976, 513.3839392728665, 561.301180030856, 577.8064348443235, 580.2225018475264, 618.2241287184554, 7184.425245980414, 7187.101612298463, 7255.201927787939, 7290.5625662460425, 7333.559986400833, 513.2967834067351, 575.5884542552095, 578.3002566894105, 622.3185611755121, 627.7740013595102, 3287.983560082232, 3705.6109108543756, 3854.614538546179, 3858.749181303206, 3997.340438827619, 79151.0212244687, 93687.0, 93801.53099234597, 93859.0, 96926.51536742272, 69447.95828062583, 74365.62817185988, 75260.62369688226, 75356.62321688466, 76054.61972690102, 63353.373012898395, 68224.3177568218, 68448.63102737918, 68513.97229041568, 68655.97016044763, 7772.0, 8249.092599814136, 8329.208725171098, 8449.619767110371, 8485.787855303666, 33665.326693466486, 37293.627063729014, 38692.64575739836, 42595.36106958336, 43298.78350608227, 58778.0, 61783.38216617776, 61945.07082393858, 62093.37906621057, 62527.6873615629, 9361.906380936103, 9423.38747981379, 10743.203016471336, 11014.284071535325, 11104.167187461135, 9773.31586788918, 12165.0, 12376.504939802307, 12379.947704445223, 12788.9129424, 11414.429278535874, 11620.360880151673, 11711.648650540497, 11801.468933897824, 11870.228435151688, 9704.786901637295, 11382.886171138183, 11478.081753459535, 11698.70753231176, 11873.346965916611, 11211.159163062966, 11223.270487418291, 11224.045956093823, 11490.253133546294, 12288.262704237774, 9409.670661527087, 9533.189678877374, 9711.611535538499, 9878.506074696093, 10129.594816207564, 9254.259659227111, 9570.47362395075, 9658.468784216935, 9777.706668799947, 9906.596933672343], "value": 6829.7092324121595, "unit": "score"}], "name": "dromaeo_css", "value": 7069.556902656878, "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, 113.0, 111.0, 111.0, 113.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 117.0, 142.0, 241.0, 141.0, 142.0, 139.0, 115.0, 170.0, 114.0], "value": 147.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 188.0, 186.0, 184.0, 191.0, 257.0, 209.0, 184.0, 184.0, 182.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 58.0, 125.0, 57.0, 61.0, 57.0, 59.0, 109.0, 55.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 99.0, 133.0, 79.0, 79.0, 78.0, 103.0, 81.0, 85.0, 100.0], "value": 93.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 86.0, 83.0, 86.0, 83.0, 87.0, 83.0, 85.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 94.0, 92.0, 107.0, 146.0, 95.0, 93.0, 92.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 133.0, 84.0, 85.0, 86.0, 84.0, 84.0, 84.0, 84.0, 85.0], "value": 91.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 59.0, 60.0, 58.0, 61.0, 119.0, 58.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 68.0, 74.0, 74.0, 70.0, 67.0, 68.0, 69.0, 69.0, 72.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 88.0, 85.0, 83.0, 82.0, 86.0, 85.0, 85.0, 101.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 148.0, 132.0, 134.0, 131.0, 140.0, 146.0, 148.0, 275.0, 142.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [206.0, 225.0, 167.0, 167.0, 165.0, 164.0, 164.0, 173.0, 167.0, 166.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 68.0, 65.0, 66.0, 64.0, 67.0, 67.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1515.3000000000002, "alertThreshold": 2.0}]} 12:57:34 INFO - Return code: 0 12:57:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:57:34 ERROR - # TBPL SUCCESS # 12:57:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:57:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:57:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:57:34 INFO - 2016-05-11 12:57:34.978 screenresolution[1967:13686] starting screenresolution argv=screenresolution get 12:57:34 INFO - 2016-05-11 12:57:34.981 screenresolution[1967:13686] Display 0: 1600x1200x32@60 12:57:34 INFO - 2016-05-11 12:57:34.987 screenresolution[1968:13689] starting screenresolution argv=screenresolution list 12:57:34 INFO - Available Modes on Display 0 12:57:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:57:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:57:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:57:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:57:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:57:35 INFO - Intel Iris: 12:57:35 INFO - Chipset Model: Intel Iris 12:57:35 INFO - Type: GPU 12:57:35 INFO - Bus: Built-In 12:57:35 INFO - VRAM (Dynamic, Max): 1536 MB 12:57:35 INFO - Vendor: Intel (0x8086) 12:57:35 INFO - Device ID: 0x0a2e 12:57:35 INFO - Revision ID: 0x0009 12:57:35 INFO - Displays: 12:57:35 INFO - Display: 12:57:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:57:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:57:35 INFO - Display Serial Number: 1600x1200 60 12:57:35 INFO - Main Display: Yes 12:57:35 INFO - Mirror: Off 12:57:35 INFO - Online: Yes 12:57:35 INFO - Rotation: Supported 12:57:35 INFO - Return code: 0 12:57:35 INFO - Running post-action listener: _resource_record_post_action 12:57:35 INFO - ##### 12:57:35 INFO - ##### Finished run-tests step (success) 12:57:35 INFO - ##### 12:57:35 INFO - Running post-run listener: _resource_record_post_run 12:57:36 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 278528; Write bytes: 691529728; Read time: 7; Write time: 2198 12:57:36 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 373254656; Write bytes: 367371776; Read time: 28035; Write time: 658 12:57:36 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 278528; Write bytes: 295117312; Read time: 7; Write time: 1483 12:57:36 INFO - Running post-run listener: _upload_blobber_files 12:57:36 INFO - Blob upload gear active. 12:57:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:57:36 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 12:57:36 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'] 12:57:36 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 12:57:36 INFO - (blobuploader) - INFO - Open directory for files ... 12:57:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:57:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:57:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:57:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:57:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:57:37 INFO - (blobuploader) - INFO - Done attempting. 12:57:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:57:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:57:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:57:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:57:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:57:38 INFO - (blobuploader) - INFO - Done attempting. 12:57:38 INFO - (blobuploader) - INFO - Iteration through files over. 12:57:38 INFO - Return code: 0 12:57:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:57:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:57:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b52e20320d650c07889c87918615c3706b165e5000370f6a463720b6845a301bdb85aa7cc626af80f6bcbb40d2195ec3c70e0220cb3d8d4a92449e560e318dfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bab7af6e12d4fcb8083275e71ba6de76039c5dbbdd3e85769b9539b3cd76dcd318d9f10c890e5b2f6c034a37dc53282e5b9f1518c1f253213550126517fa383"} 12:57:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:57:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:57:38 INFO - Contents: 12:57:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b52e20320d650c07889c87918615c3706b165e5000370f6a463720b6845a301bdb85aa7cc626af80f6bcbb40d2195ec3c70e0220cb3d8d4a92449e560e318dfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bab7af6e12d4fcb8083275e71ba6de76039c5dbbdd3e85769b9539b3cd76dcd318d9f10c890e5b2f6c034a37dc53282e5b9f1518c1f253213550126517fa383"} 12:57:38 INFO - Running post-run listener: copy_logs_to_upload_dir 12:57:38 INFO - Copying logs to upload dir... 12:57:38 INFO - mkdir: /builds/slave/test/build/upload/logs 12:57:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.841453 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-11 12:57:38.426175) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 12:57:38.429610) ========= 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.wXszDkKqkj/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.cu6IdakZ2i/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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/b52e20320d650c07889c87918615c3706b165e5000370f6a463720b6845a301bdb85aa7cc626af80f6bcbb40d2195ec3c70e0220cb3d8d4a92449e560e318dfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bab7af6e12d4fcb8083275e71ba6de76039c5dbbdd3e85769b9539b3cd76dcd318d9f10c890e5b2f6c034a37dc53282e5b9f1518c1f253213550126517fa383"} build_url:https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/UbS2HzG0Tz6Zr789Rc0g4g/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/b52e20320d650c07889c87918615c3706b165e5000370f6a463720b6845a301bdb85aa7cc626af80f6bcbb40d2195ec3c70e0220cb3d8d4a92449e560e318dfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5bab7af6e12d4fcb8083275e71ba6de76039c5dbbdd3e85769b9539b3cd76dcd318d9f10c890e5b2f6c034a37dc53282e5b9f1518c1f253213550126517fa383"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 12:57:38.457541) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 12:57:38.457870) ========= 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.wXszDkKqkj/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.cu6IdakZ2i/Listeners TMPDIR=/var/folders/kk/yyt80n5134xftnxh1jqzdjsw00000w/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.005073 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 12:57:38.506217) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 12:57:38.506560) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 12:57:38.506897) ========= ========= Total master_lag: 0.17 =========